builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1468246278.63 results: success (0) buildid: 20160711043336 builduid: 6ba6f6a1efa64033b2eca60d11d1eaaf revision: 1bee8d2da23ec894980fb14f516210fd83e5b9f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.634628) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.635473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.635796) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.652045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.652730) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 07:11:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.2M=0s 2016-07-11 07:11:19 (89.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102727 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.784538) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.784869) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017963 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.841624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:18.842074) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1bee8d2da23ec894980fb14f516210fd83e5b9f9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1bee8d2da23ec894980fb14f516210fd83e5b9f9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 07:11:19,428 truncating revision to first 12 chars 2016-07-11 07:11:19,428 Setting DEBUG logging. 2016-07-11 07:11:19,428 attempt 1/10 2016-07-11 07:11:19,428 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1bee8d2da23e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 07:11:19,736 unpacking tar archive at: mozilla-central-1bee8d2da23e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.667647 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:19.525651) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:19.525965) ========= script_repo_revision: 1bee8d2da23ec894980fb14f516210fd83e5b9f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:19.526397) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:19.526710) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 07:11:19.537590) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-07-11 07:11:19.537897) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:11:20 INFO - MultiFileLogger online at 20160711 07:11:20 in /builds/slave/test 07:11:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:11:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:11:20 INFO - {'append_to_log': False, 07:11:20 INFO - 'base_work_dir': '/builds/slave/test', 07:11:20 INFO - 'blob_upload_branch': 'Firefox', 07:11:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:11:20 INFO - 'branch': 'Firefox', 07:11:20 INFO - 'buildbot_json_path': 'buildprops.json', 07:11:20 INFO - 'config_files': ('talos/linux_config.py',), 07:11:20 INFO - 'default_actions': ('clobber', 07:11:20 INFO - 'read-buildbot-config', 07:11:20 INFO - 'download-and-extract', 07:11:20 INFO - 'populate-webroot', 07:11:20 INFO - 'create-virtualenv', 07:11:20 INFO - 'install', 07:11:20 INFO - 'run-tests'), 07:11:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:11:20 INFO - 'download_minidump_stackwalk': True, 07:11:20 INFO - 'download_symbols': 'ondemand', 07:11:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:11:20 INFO - 'tooltool.py': '/tools/tooltool.py', 07:11:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:11:20 INFO - '/tools/misc-python/virtualenv.py')}, 07:11:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:11:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:11:20 INFO - 'installer_path': 'installer.exe', 07:11:20 INFO - 'log_level': 'info', 07:11:20 INFO - 'log_name': 'talos', 07:11:20 INFO - 'log_to_console': True, 07:11:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:11:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:11:20 INFO - 'opt_config_files': (), 07:11:20 INFO - 'pip_index': False, 07:11:20 INFO - 'sps_profile': False, 07:11:20 INFO - 'sps_profile_interval': 0, 07:11:20 INFO - 'suite': 'dromaeojs', 07:11:20 INFO - 'system_bits': '32', 07:11:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:11:20 INFO - 'title': 'talos-linux64-ix-069', 07:11:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:11:20 INFO - 'use_talos_json': True, 07:11:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:11:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:11:20 INFO - 'work_dir': 'build'} 07:11:20 INFO - ##### 07:11:20 INFO - ##### Running clobber step. 07:11:20 INFO - ##### 07:11:20 INFO - Running pre-action listener: _resource_record_pre_action 07:11:20 INFO - Running main action method: clobber 07:11:20 INFO - rmtree: /builds/slave/test/build 07:11:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:11:20 INFO - Running post-action listener: _resource_record_post_action 07:11:20 INFO - ##### 07:11:20 INFO - ##### Finished clobber step (success) 07:11:20 INFO - ##### 07:11:20 INFO - ##### 07:11:20 INFO - ##### Running read-buildbot-config step. 07:11:20 INFO - ##### 07:11:20 INFO - Running pre-action listener: _resource_record_pre_action 07:11:20 INFO - Running main action method: read_buildbot_config 07:11:20 INFO - Using buildbot properties: 07:11:20 INFO - { 07:11:20 INFO - "project": "", 07:11:20 INFO - "product": "firefox", 07:11:20 INFO - "script_repo_revision": "production", 07:11:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:11:20 INFO - "repository": "", 07:11:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:11:20 INFO - "buildid": "20160711043336", 07:11:20 INFO - "pgo_build": "True", 07:11:20 INFO - "basedir": "/builds/slave/test", 07:11:20 INFO - "buildnumber": 497, 07:11:20 INFO - "slavename": "talos-linux64-ix-069", 07:11:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:11:20 INFO - "platform": "ubuntu64_hw", 07:11:20 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:11:20 INFO - "branch": "mozilla-central", 07:11:20 INFO - "repo_path": "mozilla-central", 07:11:20 INFO - "revision": "1bee8d2da23ec894980fb14f516210fd83e5b9f9", 07:11:20 INFO - "stage_platform": "linux64-pgo", 07:11:20 INFO - "builduid": "6ba6f6a1efa64033b2eca60d11d1eaaf", 07:11:20 INFO - "slavebuilddir": "test" 07:11:20 INFO - } 07:11:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 07:11:20 INFO - Running post-action listener: _resource_record_post_action 07:11:20 INFO - ##### 07:11:20 INFO - ##### Finished read-buildbot-config step (success) 07:11:20 INFO - ##### 07:11:20 INFO - ##### 07:11:20 INFO - ##### Running download-and-extract step. 07:11:20 INFO - ##### 07:11:20 INFO - Running pre-action listener: _resource_record_pre_action 07:11:20 INFO - Running main action method: download_and_extract 07:11:20 INFO - mkdir: /builds/slave/test/build/tests 07:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:20 INFO - https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:11:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:11:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:11:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:11:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:11:27 INFO - Downloaded 1361 bytes. 07:11:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 07:11:27 INFO - Using the following test package requirements: 07:11:27 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:11:27 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:11:27 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:11:27 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'jsshell-linux-x86_64.zip'], 07:11:27 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:11:27 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 07:11:27 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:11:27 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:11:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:11:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 07:11:27 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:11:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:27 INFO - https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:11:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:11:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:11:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:11:30 INFO - Downloaded 24304433 bytes. 07:11:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:11:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:11:31 INFO - Return code: 0 07:11:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:31 INFO - https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:11:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:11:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:11:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 07:11:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:11:44 INFO - Downloaded 24304433 bytes. 07:11:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:11:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:11:45 INFO - Return code: 0 07:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:45 INFO - https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:11:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:11:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:11:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 07:11:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:11:46 INFO - Downloaded 14403333 bytes. 07:11:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:11:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:11:46 INFO - Return code: 0 07:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:46 INFO - https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:11:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:11:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:11:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:11:49 INFO - Downloaded 60482360 bytes. 07:11:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:11:49 INFO - mkdir: /builds/slave/test/properties 07:11:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:11:49 INFO - Writing to file /builds/slave/test/properties/build_url 07:11:49 INFO - Contents: 07:11:49 INFO - build_url:https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 07:11:49 INFO - Running post-action listener: _resource_record_post_action 07:11:49 INFO - Running post-action listener: set_extra_try_arguments 07:11:49 INFO - ##### 07:11:49 INFO - ##### Finished download-and-extract step (success) 07:11:49 INFO - ##### 07:11:49 INFO - ##### 07:11:49 INFO - ##### Running populate-webroot step. 07:11:49 INFO - ##### 07:11:49 INFO - Running pre-action listener: _resource_record_pre_action 07:11:49 INFO - Running main action method: populate_webroot 07:11:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:11:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:11:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:11:49 INFO - '--tppagecycles', 07:11:49 INFO - '7'], 07:11:49 INFO - 'tests': ['tsvgm']}}, 07:11:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:11:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:11:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:11:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:11:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['damp', 'tps']}, 07:11:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'tests': ['damp', 'tps']}, 07:11:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['dromaeo_dom']}, 07:11:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:11:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['basic_compositor_video']}, 07:11:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:11:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['a11yr', 07:11:49 INFO - 'ts_paint', 07:11:49 INFO - 'tpaint', 07:11:49 INFO - 'sessionrestore', 07:11:49 INFO - 'sessionrestore_no_auto_restore', 07:11:49 INFO - 'tabpaint']}, 07:11:49 INFO - 'other-e10s': {'tests': ['a11yr', 07:11:49 INFO - 'ts_paint', 07:11:49 INFO - 'tpaint', 07:11:49 INFO - 'sessionrestore', 07:11:49 INFO - 'sessionrestore_no_auto_restore', 07:11:49 INFO - 'tabpaint']}, 07:11:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:11:49 INFO - 'ts_paint', 07:11:49 INFO - 'tpaint', 07:11:49 INFO - 'sessionrestore', 07:11:49 INFO - 'sessionrestore_no_auto_restore', 07:11:49 INFO - 'tabpaint']}, 07:11:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:11:49 INFO - 'ts_paint', 07:11:49 INFO - 'tpaint', 07:11:49 INFO - 'sessionrestore', 07:11:49 INFO - 'sessionrestore_no_auto_restore', 07:11:49 INFO - 'tabpaint']}, 07:11:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['a11yr', 07:11:49 INFO - 'ts_paint', 07:11:49 INFO - 'tpaint', 07:11:49 INFO - 'sessionrestore', 07:11:49 INFO - 'sessionrestore_no_auto_restore', 07:11:49 INFO - 'tabpaint']}, 07:11:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['a11yr', 07:11:49 INFO - 'ts_paint', 07:11:49 INFO - 'tpaint', 07:11:49 INFO - 'sessionrestore', 07:11:49 INFO - 'sessionrestore_no_auto_restore', 07:11:49 INFO - 'tabpaint']}, 07:11:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['tsvgx', 07:11:49 INFO - 'tsvgr_opacity', 07:11:49 INFO - 'tart', 07:11:49 INFO - 'tscrollx', 07:11:49 INFO - 'cart']}, 07:11:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:11:49 INFO - 'tsvgr_opacity', 07:11:49 INFO - 'tart', 07:11:49 INFO - 'tscrollx', 07:11:49 INFO - 'cart']}, 07:11:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'talos_options': ['--disable-e10s'], 07:11:49 INFO - 'tests': ['tp5o']}, 07:11:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'tests': ['tp5o']}, 07:11:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'talos_options': ['--disable-e10s', 07:11:49 INFO - '--xperf_path', 07:11:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:49 INFO - 'tests': ['tp5n']}, 07:11:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:11:49 INFO - 'talos_options': ['--xperf_path', 07:11:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:49 INFO - 'tests': ['tp5n']}}, 07:11:49 INFO - 'talos.zip': {'path': '', 07:11:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:11:49 INFO - Running post-action listener: _resource_record_post_action 07:11:49 INFO - ##### 07:11:49 INFO - ##### Finished populate-webroot step (success) 07:11:49 INFO - ##### 07:11:49 INFO - ##### 07:11:49 INFO - ##### Running create-virtualenv step. 07:11:49 INFO - ##### 07:11:49 INFO - Running pre-action listener: _resource_record_pre_action 07:11:49 INFO - Running main action method: create_virtualenv 07:11:49 INFO - Creating virtualenv /builds/slave/test/build/venv 07:11:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:11:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:11:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:11:50 INFO - Using real prefix '/usr' 07:11:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:11:50 INFO - Installing distribute.............................................................................................................................................................................................done. 07:11:52 INFO - Installing pip.................done. 07:11:52 INFO - Return code: 0 07:11:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:11:52 INFO - Using env: {'DISPLAY': ':0', 07:11:52 INFO - 'HOME': '/home/cltbld', 07:11:52 INFO - 'LANG': 'en_US.UTF-8', 07:11:52 INFO - 'LANGUAGE': 'en_US:en', 07:11:52 INFO - 'LOGNAME': 'cltbld', 07:11:52 INFO - 'MAIL': '/var/mail/cltbld', 07:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:52 INFO - 'MOZ_NO_REMOTE': '1', 07:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:52 INFO - 'NO_EM_RESTART': '1', 07:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:52 INFO - 'PWD': '/builds/slave/test', 07:11:52 INFO - 'SHELL': '/bin/bash', 07:11:52 INFO - 'SHLVL': '1', 07:11:52 INFO - 'TERM': 'linux', 07:11:52 INFO - 'TMOUT': '86400', 07:11:52 INFO - 'USER': 'cltbld', 07:11:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:52 INFO - '_': '/tools/buildbot/bin/python'} 07:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:11:52 INFO - Cleaning up... 07:11:52 INFO - Return code: 0 07:11:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:11:52 INFO - Using env: {'DISPLAY': ':0', 07:11:52 INFO - 'HOME': '/home/cltbld', 07:11:52 INFO - 'LANG': 'en_US.UTF-8', 07:11:52 INFO - 'LANGUAGE': 'en_US:en', 07:11:52 INFO - 'LOGNAME': 'cltbld', 07:11:52 INFO - 'MAIL': '/var/mail/cltbld', 07:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:52 INFO - 'MOZ_NO_REMOTE': '1', 07:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:52 INFO - 'NO_EM_RESTART': '1', 07:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:52 INFO - 'PWD': '/builds/slave/test', 07:11:52 INFO - 'SHELL': '/bin/bash', 07:11:52 INFO - 'SHLVL': '1', 07:11:52 INFO - 'TERM': 'linux', 07:11:52 INFO - 'TMOUT': '86400', 07:11:52 INFO - 'USER': 'cltbld', 07:11:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:52 INFO - '_': '/tools/buildbot/bin/python'} 07:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:52 INFO - Downloading/unpacking psutil>=3.1.1 07:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:11:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:54 INFO - Installing collected packages: psutil 07:11:54 INFO - Running setup.py install for psutil 07:11:54 INFO - building 'psutil._psutil_linux' extension 07:11:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:11:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:11:55 INFO - building 'psutil._psutil_posix' extension 07:11:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:11:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:11:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:55 INFO - Successfully installed psutil 07:11:55 INFO - Cleaning up... 07:11:55 INFO - Return code: 0 07:11:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:11:55 INFO - Using env: {'DISPLAY': ':0', 07:11:55 INFO - 'HOME': '/home/cltbld', 07:11:55 INFO - 'LANG': 'en_US.UTF-8', 07:11:55 INFO - 'LANGUAGE': 'en_US:en', 07:11:55 INFO - 'LOGNAME': 'cltbld', 07:11:55 INFO - 'MAIL': '/var/mail/cltbld', 07:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:55 INFO - 'MOZ_NO_REMOTE': '1', 07:11:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:55 INFO - 'NO_EM_RESTART': '1', 07:11:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:55 INFO - 'PWD': '/builds/slave/test', 07:11:55 INFO - 'SHELL': '/bin/bash', 07:11:55 INFO - 'SHLVL': '1', 07:11:55 INFO - 'TERM': 'linux', 07:11:55 INFO - 'TMOUT': '86400', 07:11:55 INFO - 'USER': 'cltbld', 07:11:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:55 INFO - '_': '/tools/buildbot/bin/python'} 07:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:11:57 INFO - Installing collected packages: mozsystemmonitor 07:11:57 INFO - Running setup.py install for mozsystemmonitor 07:11:57 INFO - Successfully installed mozsystemmonitor 07:11:57 INFO - Cleaning up... 07:11:57 INFO - Return code: 0 07:11:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:11:57 INFO - Using env: {'DISPLAY': ':0', 07:11:57 INFO - 'HOME': '/home/cltbld', 07:11:57 INFO - 'LANG': 'en_US.UTF-8', 07:11:57 INFO - 'LANGUAGE': 'en_US:en', 07:11:57 INFO - 'LOGNAME': 'cltbld', 07:11:57 INFO - 'MAIL': '/var/mail/cltbld', 07:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:57 INFO - 'MOZ_NO_REMOTE': '1', 07:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:11:57 INFO - 'NO_EM_RESTART': '1', 07:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:57 INFO - 'PWD': '/builds/slave/test', 07:11:57 INFO - 'SHELL': '/bin/bash', 07:11:57 INFO - 'SHLVL': '1', 07:11:57 INFO - 'TERM': 'linux', 07:11:57 INFO - 'TMOUT': '86400', 07:11:57 INFO - 'USER': 'cltbld', 07:11:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:57 INFO - '_': '/tools/buildbot/bin/python'} 07:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:57 INFO - Downloading/unpacking jsonschema==2.5.1 07:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:12:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:00 INFO - Downloading functools32-3.2.3-2.tar.gz 07:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:12:00 INFO - warning: no files found matching '*.txt' 07:12:00 INFO - no previously-included directories found matching 'build' 07:12:00 INFO - no previously-included directories found matching 'dist' 07:12:00 INFO - no previously-included directories found matching '.git*' 07:12:00 INFO - Installing collected packages: jsonschema, functools32 07:12:00 INFO - Running setup.py install for functools32 07:12:00 INFO - warning: no files found matching '*.txt' 07:12:00 INFO - no previously-included directories found matching 'build' 07:12:00 INFO - no previously-included directories found matching 'dist' 07:12:00 INFO - no previously-included directories found matching '.git*' 07:12:00 INFO - Successfully installed jsonschema functools32 07:12:00 INFO - Cleaning up... 07:12:00 INFO - Return code: 0 07:12:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:12:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:12:00 INFO - Using env: {'DISPLAY': ':0', 07:12:00 INFO - 'HOME': '/home/cltbld', 07:12:00 INFO - 'LANG': 'en_US.UTF-8', 07:12:00 INFO - 'LANGUAGE': 'en_US:en', 07:12:00 INFO - 'LOGNAME': 'cltbld', 07:12:00 INFO - 'MAIL': '/var/mail/cltbld', 07:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:00 INFO - 'MOZ_NO_REMOTE': '1', 07:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:00 INFO - 'NO_EM_RESTART': '1', 07:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:00 INFO - 'PWD': '/builds/slave/test', 07:12:00 INFO - 'SHELL': '/bin/bash', 07:12:00 INFO - 'SHLVL': '1', 07:12:00 INFO - 'TERM': 'linux', 07:12:00 INFO - 'TMOUT': '86400', 07:12:00 INFO - 'USER': 'cltbld', 07:12:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:00 INFO - '_': '/tools/buildbot/bin/python'} 07:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:00 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:02 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:03 INFO - Downloading docopt-0.6.1.tar.gz 07:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:03 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:03 INFO - Running setup.py install for blobuploader 07:12:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:03 INFO - Running setup.py install for requests 07:12:03 INFO - Running setup.py install for docopt 07:12:03 INFO - Successfully installed blobuploader requests docopt 07:12:03 INFO - Cleaning up... 07:12:03 INFO - Return code: 0 07:12:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:03 INFO - Using env: {'DISPLAY': ':0', 07:12:03 INFO - 'HOME': '/home/cltbld', 07:12:03 INFO - 'LANG': 'en_US.UTF-8', 07:12:03 INFO - 'LANGUAGE': 'en_US:en', 07:12:03 INFO - 'LOGNAME': 'cltbld', 07:12:03 INFO - 'MAIL': '/var/mail/cltbld', 07:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:03 INFO - 'MOZ_NO_REMOTE': '1', 07:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:03 INFO - 'NO_EM_RESTART': '1', 07:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:03 INFO - 'PWD': '/builds/slave/test', 07:12:03 INFO - 'SHELL': '/bin/bash', 07:12:03 INFO - 'SHLVL': '1', 07:12:03 INFO - 'TERM': 'linux', 07:12:03 INFO - 'TMOUT': '86400', 07:12:03 INFO - 'USER': 'cltbld', 07:12:03 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:03 INFO - '_': '/tools/buildbot/bin/python'} 07:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:04 INFO - Running setup.py (path:/tmp/pip-_VglkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:04 INFO - Running setup.py (path:/tmp/pip-QF3tpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:04 INFO - Running setup.py (path:/tmp/pip-vNXbui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:04 INFO - Running setup.py (path:/tmp/pip-0HVJAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:04 INFO - Running setup.py (path:/tmp/pip-OEs0O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:04 INFO - Running setup.py (path:/tmp/pip-X6yp9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:04 INFO - Running setup.py (path:/tmp/pip-26HAeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:04 INFO - Running setup.py (path:/tmp/pip-V5zWNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:04 INFO - Running setup.py (path:/tmp/pip-G0lrTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:04 INFO - Running setup.py (path:/tmp/pip-nrNHaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:04 INFO - Running setup.py (path:/tmp/pip-jDabeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:04 INFO - Running setup.py (path:/tmp/pip-60FktB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:05 INFO - Running setup.py (path:/tmp/pip-gRG4j9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:05 INFO - Running setup.py (path:/tmp/pip-R10zUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:05 INFO - Running setup.py (path:/tmp/pip-iZS6_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:05 INFO - Running setup.py (path:/tmp/pip-YKr6g7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:05 INFO - Running setup.py (path:/tmp/pip-7Kk6zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:05 INFO - Running setup.py install for manifestparser 07:12:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:05 INFO - Running setup.py install for mozcrash 07:12:05 INFO - Running setup.py install for mozdebug 07:12:05 INFO - Running setup.py install for mozdevice 07:12:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:12:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:05 INFO - Running setup.py install for mozfile 07:12:05 INFO - Running setup.py install for mozhttpd 07:12:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozinfo 07:12:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozInstall 07:12:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozleak 07:12:06 INFO - Running setup.py install for mozlog 07:12:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for moznetwork 07:12:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozprocess 07:12:06 INFO - Running setup.py install for mozprofile 07:12:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozrunner 07:12:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:06 INFO - Running setup.py install for mozscreenshot 07:12:06 INFO - Running setup.py install for moztest 07:12:07 INFO - Running setup.py install for mozversion 07:12:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:07 INFO - Cleaning up... 07:12:07 INFO - Return code: 0 07:12:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:12:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:07 INFO - Using env: {'DISPLAY': ':0', 07:12:07 INFO - 'HOME': '/home/cltbld', 07:12:07 INFO - 'LANG': 'en_US.UTF-8', 07:12:07 INFO - 'LANGUAGE': 'en_US:en', 07:12:07 INFO - 'LOGNAME': 'cltbld', 07:12:07 INFO - 'MAIL': '/var/mail/cltbld', 07:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:07 INFO - 'MOZ_NO_REMOTE': '1', 07:12:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:07 INFO - 'NO_EM_RESTART': '1', 07:12:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:07 INFO - 'PWD': '/builds/slave/test', 07:12:07 INFO - 'SHELL': '/bin/bash', 07:12:07 INFO - 'SHLVL': '1', 07:12:07 INFO - 'TERM': 'linux', 07:12:07 INFO - 'TMOUT': '86400', 07:12:07 INFO - 'USER': 'cltbld', 07:12:07 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:07 INFO - '_': '/tools/buildbot/bin/python'} 07:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:07 INFO - Running setup.py (path:/tmp/pip-l4CPaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:07 INFO - Running setup.py (path:/tmp/pip-rRQ7gV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:07 INFO - Running setup.py (path:/tmp/pip-2BLTA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:07 INFO - Running setup.py (path:/tmp/pip-SVw7SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:07 INFO - Running setup.py (path:/tmp/pip-3S9E3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:07 INFO - Running setup.py (path:/tmp/pip-BJ_Un4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:07 INFO - Running setup.py (path:/tmp/pip-OIEdXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:07 INFO - Running setup.py (path:/tmp/pip-hH02hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:08 INFO - Running setup.py (path:/tmp/pip-Dj_dLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:08 INFO - Running setup.py (path:/tmp/pip-KiopyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:08 INFO - Running setup.py (path:/tmp/pip-v_Epip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:08 INFO - Running setup.py (path:/tmp/pip-wE27b9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:08 INFO - Running setup.py (path:/tmp/pip-x4Px8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:08 INFO - Running setup.py (path:/tmp/pip-QOV7E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:08 INFO - Running setup.py (path:/tmp/pip-07OnA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:08 INFO - Running setup.py (path:/tmp/pip-9Yobs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:08 INFO - Running setup.py (path:/tmp/pip-4RC6nK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:10 INFO - Downloading blessings-1.6.tar.gz 07:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:12:10 INFO - Installing collected packages: blessings 07:12:10 INFO - Running setup.py install for blessings 07:12:10 INFO - Successfully installed blessings 07:12:10 INFO - Cleaning up... 07:12:10 INFO - Return code: 0 07:12:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:12:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:11 INFO - Reading from file tmpfile_stdout 07:12:11 INFO - Current package versions: 07:12:11 INFO - argparse == 1.2.1 07:12:11 INFO - blessings == 1.6 07:12:11 INFO - blobuploader == 1.2.4 07:12:11 INFO - docopt == 0.6.1 07:12:11 INFO - functools32 == 3.2.3-2 07:12:11 INFO - jsonschema == 2.5.1 07:12:11 INFO - manifestparser == 1.1 07:12:11 INFO - mozInstall == 1.12 07:12:11 INFO - mozcrash == 0.17 07:12:11 INFO - mozdebug == 0.1 07:12:11 INFO - mozdevice == 0.48 07:12:11 INFO - mozfile == 1.2 07:12:11 INFO - mozhttpd == 0.7 07:12:11 INFO - mozinfo == 0.9 07:12:11 INFO - mozleak == 0.1 07:12:11 INFO - mozlog == 3.2 07:12:11 INFO - moznetwork == 0.27 07:12:11 INFO - mozprocess == 0.23 07:12:11 INFO - mozprofile == 0.28 07:12:11 INFO - mozrunner == 6.12 07:12:11 INFO - mozscreenshot == 0.1 07:12:11 INFO - mozsystemmonitor == 0.3 07:12:11 INFO - moztest == 0.7 07:12:11 INFO - mozversion == 1.4 07:12:11 INFO - psutil == 3.1.1 07:12:11 INFO - requests == 1.2.3 07:12:11 INFO - wsgiref == 0.1.2 07:12:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:12:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:11 INFO - Using env: {'DISPLAY': ':0', 07:12:11 INFO - 'HOME': '/home/cltbld', 07:12:11 INFO - 'LANG': 'en_US.UTF-8', 07:12:11 INFO - 'LANGUAGE': 'en_US:en', 07:12:11 INFO - 'LOGNAME': 'cltbld', 07:12:11 INFO - 'MAIL': '/var/mail/cltbld', 07:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:11 INFO - 'MOZ_NO_REMOTE': '1', 07:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:11 INFO - 'NO_EM_RESTART': '1', 07:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:11 INFO - 'PWD': '/builds/slave/test', 07:12:11 INFO - 'SHELL': '/bin/bash', 07:12:11 INFO - 'SHLVL': '1', 07:12:11 INFO - 'TERM': 'linux', 07:12:11 INFO - 'TMOUT': '86400', 07:12:11 INFO - 'USER': 'cltbld', 07:12:11 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:11 INFO - '_': '/tools/buildbot/bin/python'} 07:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:12:11 INFO - Cleaning up... 07:12:11 INFO - Return code: 0 07:12:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:12:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:12:11 INFO - Using env: {'DISPLAY': ':0', 07:12:11 INFO - 'HOME': '/home/cltbld', 07:12:11 INFO - 'LANG': 'en_US.UTF-8', 07:12:11 INFO - 'LANGUAGE': 'en_US:en', 07:12:11 INFO - 'LOGNAME': 'cltbld', 07:12:11 INFO - 'MAIL': '/var/mail/cltbld', 07:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:11 INFO - 'MOZ_NO_REMOTE': '1', 07:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:11 INFO - 'NO_EM_RESTART': '1', 07:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:11 INFO - 'PWD': '/builds/slave/test', 07:12:11 INFO - 'SHELL': '/bin/bash', 07:12:11 INFO - 'SHLVL': '1', 07:12:11 INFO - 'TERM': 'linux', 07:12:11 INFO - 'TMOUT': '86400', 07:12:11 INFO - 'USER': 'cltbld', 07:12:11 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:11 INFO - '_': '/tools/buildbot/bin/python'} 07:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:12:11 INFO - Cleaning up... 07:12:11 INFO - Return code: 0 07:12:11 INFO - Running post-action listener: _resource_record_post_action 07:12:11 INFO - Running post-action listener: _start_resource_monitoring 07:12:11 INFO - Starting resource monitoring. 07:12:11 INFO - ##### 07:12:11 INFO - ##### Finished create-virtualenv step (success) 07:12:11 INFO - ##### 07:12:11 INFO - ##### 07:12:11 INFO - ##### Running install step. 07:12:11 INFO - ##### 07:12:11 INFO - Running pre-action listener: _resource_record_pre_action 07:12:11 INFO - Running main action method: install 07:12:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:11 INFO - Reading from file tmpfile_stdout 07:12:11 INFO - Detecting whether we're running mozinstall >=1.0... 07:12:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:12:11 INFO - Reading from file tmpfile_stdout 07:12:11 INFO - Output received: 07:12:11 INFO - Usage: mozinstall [options] installer 07:12:11 INFO - Options: 07:12:11 INFO - -h, --help show this help message and exit 07:12:11 INFO - -d DEST, --destination=DEST 07:12:11 INFO - Directory to install application into. [default: 07:12:11 INFO - "/builds/slave/test"] 07:12:11 INFO - --app=APP Application being installed. [default: firefox] 07:12:11 INFO - mkdir: /builds/slave/test/build/application 07:12:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:12:29 INFO - Reading from file tmpfile_stdout 07:12:29 INFO - Output received: 07:12:29 INFO - /builds/slave/test/build/application/firefox/firefox 07:12:29 INFO - Running post-action listener: _resource_record_post_action 07:12:29 INFO - ##### 07:12:29 INFO - ##### Finished install step (success) 07:12:29 INFO - ##### 07:12:29 INFO - ##### 07:12:29 INFO - ##### Running run-tests step. 07:12:29 INFO - ##### 07:12:29 INFO - Running pre-action listener: _resource_record_pre_action 07:12:29 INFO - Running main action method: run_tests 07:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:12:29 INFO - Python 2.7.3 07:12:29 INFO - Return code: 0 07:12:29 INFO - grabbing minidump binary from tooltool 07:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b23b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16b3740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:12:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:12:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:12:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:12:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:12:29 INFO - Return code: 0 07:12:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:12:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:12:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:12:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:12:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:12:29 INFO - Using env: {'DISPLAY': ':0', 07:12:29 INFO - 'HOME': '/home/cltbld', 07:12:29 INFO - 'LANG': 'en_US.UTF-8', 07:12:29 INFO - 'LANGUAGE': 'en_US:en', 07:12:29 INFO - 'LOGNAME': 'cltbld', 07:12:29 INFO - 'MAIL': '/var/mail/cltbld', 07:12:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:12:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:29 INFO - 'MOZ_NO_REMOTE': '1', 07:12:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:12:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:29 INFO - 'NO_EM_RESTART': '1', 07:12:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:29 INFO - 'PWD': '/builds/slave/test', 07:12:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:12:29 INFO - 'SHELL': '/bin/bash', 07:12:29 INFO - 'SHLVL': '1', 07:12:29 INFO - 'TERM': 'linux', 07:12:29 INFO - 'TMOUT': '86400', 07:12:29 INFO - 'USER': 'cltbld', 07:12:29 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224', 07:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:29 INFO - '_': '/tools/buildbot/bin/python'} 07:12:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:12:29 INFO - mozversion application_buildid: 20160711043336 07:12:29 INFO - mozversion application_changeset: 1bee8d2da23ec894980fb14f516210fd83e5b9f9 07:12:29 INFO - mozversion application_display_name: Nightly 07:12:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:12:29 INFO - mozversion application_name: Firefox 07:12:29 INFO - mozversion application_remotingname: firefox 07:12:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:12:29 INFO - mozversion application_vendor: Mozilla 07:12:29 INFO - mozversion application_version: 50.0a1 07:12:29 INFO - mozversion platform_buildid: 20160711043336 07:12:29 INFO - mozversion platform_changeset: 1bee8d2da23ec894980fb14f516210fd83e5b9f9 07:12:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:12:29 INFO - mozversion platform_version: 50.0a1 07:12:29 INFO - using testdate: 1468246349 07:12:29 INFO - actual date: 1468246349 07:12:29 INFO - starting webserver on 'localhost:57364' 07:12:29 INFO - SUITE-START | Running 2 tests 07:12:29 INFO - TEST-START | dromaeo_css 07:12:29 INFO - operating with platform_type : linux_ 07:12:29 INFO - Initialising browser for dromaeo_css test... 07:12:29 INFO - TEST-INFO | started process 3433 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpofDLtR/profile http://localhost:57364/getInfo.html) 07:12:33 INFO - PROCESS | 3433 | __metrics Screen width/height:1600/1200 07:12:33 INFO - PROCESS | 3433 | colorDepth:24 07:12:33 INFO - PROCESS | 3433 | Browser inner width/height: 1024/697 07:12:33 INFO - PROCESS | 3433 | __metrics 07:12:34 INFO - PROCESS | 3433 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:12:34 INFO - PROCESS | 3433 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:12:35 INFO - TEST-INFO | 3433: exit 0 07:12:35 INFO - Browser initialized. 07:12:35 INFO - Running cycle 1/1 for dromaeo_css test... 07:12:35 INFO - TEST-INFO | started process 3521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpofDLtR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:12:47 INFO - PROCESS | 3521 | RSS: Main: 157642752 07:12:47 INFO - PROCESS | 3521 | 07:13:38 INFO - PROCESS | 3521 | 1468246418676 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:15:46 INFO - PROCESS | 3521 | Cycle 1(1): loaded http://localhost:57364/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57364/tests/dromaeo/cssquery-ext.html) 07:15:47 INFO - PROCESS | 3521 | RSS: Main: 246984704 07:15:47 INFO - PROCESS | 3521 | 07:18:49 INFO - PROCESS | 3521 | Cycle 1(1): loaded http://localhost:57364/tests/dromaeo/cssquery-ext.html (next: http://localhost:57364/tests/dromaeo/cssquery-jquery.html) 07:18:50 INFO - PROCESS | 3521 | RSS: Main: 235679744 07:18:50 INFO - PROCESS | 3521 | 07:21:52 INFO - PROCESS | 3521 | Cycle 1(1): loaded http://localhost:57364/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57364/tests/dromaeo/cssquery-mootools.html) 07:21:53 INFO - PROCESS | 3521 | RSS: Main: 233361408 07:21:53 INFO - PROCESS | 3521 | 07:24:55 INFO - PROCESS | 3521 | Cycle 1(1): loaded http://localhost:57364/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57364/tests/dromaeo/cssquery-prototype.html) 07:24:55 INFO - PROCESS | 3521 | RSS: Main: 231976960 07:24:55 INFO - PROCESS | 3521 | 07:27:58 INFO - PROCESS | 3521 | Cycle 1(1): loaded http://localhost:57364/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57364/tests/dromaeo/cssquery-yui.html) 07:27:59 INFO - PROCESS | 3521 | RSS: Main: 226975744 07:27:59 INFO - PROCESS | 3521 | 07:31:01 INFO - PROCESS | 3521 | Cycle 1(1): loaded http://localhost:57364/tests/dromaeo/cssquery-yui.html (next: http://localhost:57364/tests/dromaeo/cssquery-dojo.html) 07:31:02 INFO - PROCESS | 3521 | RSS: Main: 274546688 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | __start_tp_report 07:31:02 INFO - PROCESS | 3521 | _x_x_mozilla_page_load 07:31:02 INFO - PROCESS | 3521 | _x_x_mozilla_page_load_details 07:31:02 INFO - PROCESS | 3521 | |i|pagename|runs| 07:31:02 INFO - PROCESS | 3521 | |0;dojo.html;3046.147078817929;3055.572219889216;3093.7679674024453;3099.364630250798;3107.1144723753737;5196.480351964801;5302.1781623848365;5310.867228319294;5389.029974604569;5421.6746995180265;5259.737013149346;5295.602829787762;5299.8410047698535;5313.760880760366;5321.494458026481;4783.856484305467;4811.639127065467;4869.610431165498;4876.609871210312;4976.676516026465;4903.803847846082;4904.411470623512;4907.484714105021;4956.40523137224;4969;4767.237242041274;4794.544518270741;4796.040791841629;4815.951840481585;4839.588634966032;4802.927956080662;4821.252705830567;4830.6135509159185;4885.511448855122;4928.827491037832;4841.782119804618;4859.222524396079;4861.902761944777;4865.56209941107;4866.148424025781;5167.948320516784;5182.481751824825;5215.713135777531;5241.868953276198;5242.659227150223;5595.104783234663;5657.3494048184575;5685.54515638752;5688.459596338341;5688.800891968803;5687.146927960797;5716.971415142898;5721;5736.942630573682;5741.081426971665;5221.765020574083;5301.787928482856;5374.408815030343;5375.354957405098;5454.318210223722;5860.648361098348;5888.440598143169;5889.440503152194;5890.263717035371;5894.498967587803;5394;5653.773849045992;5661.716914154275;5667.433256674382;5670.489655930904;5588.692621905834;5634.690092044895;5641.9435805641415;5722.227499287626;5724.513416359656;5652.123920792284;5652.660840349592;5654.858628534237;5658.519025882763;5700.458456446631;5500.80747173855;5608.747606357723;5616.410276920946;5650.039493286151;5656.85857853557;4392.780360981938;4412.24991751403;4419.359192917009;4445.799939002688;4451.488078870907;3900.414937759302;3923.941140882889;3930.253251882133;3934.1541568562325;3971.940420893689;5248.737563121828;5351.705656188869;5398.1093119634825;5484.122540393518;5536.363318218457;5649.943500565024;5726.742296596663;5729.627574207663;5736.655800651891;5804.535637149018;5444.863878402993;5470.56235501159;5472.671639701631;5477.808276710296;5478.041342765;4870.269459581091;4901.142300097468;4939.259111133359;5000.049990501793;5055.064813009605;5975.910361344583;5980.641161530322;5987.760489580368;5989.610675306091;6027.487663548647;5707.286589176353;5713.885722285614;5720.971395142998;5722.055860783091;5722.62802917809;5788.189653448437;5896.6756828374855;5943.049112142036;5964.4035596441745;5966.910496342645;5639.323281206118;5640.717964101696;5645.350784659817;5646.2942132233475;5650.519705825051;5674.290713660793;5735.4551317624755;5736.942630573641;5739.483446489836;5741.91387129185;5436.103042998019;5548.722563871871;5576.247206627054;5603.159526070959;5650.0677388232125;5599.860003499945;5609.326880774333;5612.354579223442;5615.18579805934;5624.915626265692;5393.568514518907;5486.286782718221;5549.0289199389135;5550.86207327505;5552.361478430033;5516.696581687886;5533.723313834212;5563.833085007456;5585.972070139624;5638.323401191883;5438.945610543844;5443.618946673695;5447.237386765935;5447.455254474521;5450.291462109901 07:31:02 INFO - PROCESS | 3521 | |1;ext.html;588.9293284805824;606.5352174498586;612.8222815383542;616.2758758458809;620.1968450856141;35866.462003069944;36503.269934601325;43972.12055758887;44484;44663.33005004928;46105.53894461063;46528.30207546889;46588.767056164674;46639.76680116603;46660.3000954986;9039.683611073613;9191.126842949909;9194.31042671799;9199.678011269607;9205.263578913671;8172.591370431484;10595.417252051135;10836.674899752981;10849.783004339908;11249.60626378077;11108.944455277713;11185.49665265065;11193.608223712172;11204.831927521094;11212.271202371861;8118.75643730689;10651.573937042509;10981.286216395909;11056.668299951014;11061.336319820837;10415.739606509822;10817.567297308098;10827.945860270727;10830.891691083067;10845.349279043283;43897.78051109756;43925.78037109794;44093.118137637066;44205;44233.557664423264;3436.518887355772;3642.7996460194677;3692.5015122958594;3692.667659910621;3867.57456679765;33556.83221583877;33731.49402758961;34059.659403405894;34914.127146821535;35213.11967200815;4385.298352263622;4470.955290447087;4517.932231016537;4540.79566419512;4547.795349209294;341531;345457;345465;345565;345994;61294.38705612887;61548;61962.690186549684;61965.07052394218;62054.37945620577;36438.63561364405;36969.81515092461;37036.25927481489;38928.22143557113;64059.35940640534;15254.618634534225;15326.233688315762;15360.692786144215;15392.076475411512;15393.538193854203;11613.30320180792;11688.590899318484;11693.941530292293;11694.824577631172;11695.707607309885;19494.512637184183;19599.216031358872;19936.20255189776;20007.799922000595;20097;6774.661266936633;6789.6605169741315;7014.403775679022;7056.188538318056;7164;815.7144999250215;837.6733074100983;846.6909578003985;988.125508924607;1115.9218854680094;33665.15837104092;34344.48483272753;34510.30979380399;34943.30113397718;35053;1111.1388673777888;1139.8347239650204;1153.4405813180601;1197.6107764976418;1212.860521040074;16988.575285617957;17362.826371736122;17427.73858392125;17538.298468061388;17645.205965731573;567803;568510;570044;571315;572780;60974;61498;61554;61590.384096158465;61597;1221.578555398404;1228.0421271408316;1245.495574292413;1345.5694177863186;1367.459853357918;30961.690383096782;34644;35040.64959350374;35421.64578354287;35657.64342356543;51906;52238.47761522336;52545;52553.21170182374;52591.21113183382;425794;426570;426757;427428;428915;28751.568726469537;29077.12768616945;29347;29461;30589.23526911845;49704.75147624239;49771;49945.50054499409;51287.743561283445;51313.23030154476;41101.79449102735;42518.78740606277;42843.143137137704;42960.140797184504;43557.78221108874;42316;42335.15329693327;42797.790165737075;43268.35097473604;44069.33895991627;42629;43382.78308608437;43588.34617480804;43754.562454375045;43935.56064439443;35210.647893520734;37457.81271093627;37663.62336376711;37834;38613.80693096516;28105.859470702515;28216.22810525418;28313.292167695974;28344;28461.288467788472 07:31:02 INFO - PROCESS | 3521 | |2;jquery.html;372.12179256953567;375.249500998004;380.61177598849173;389.4664309895444;401.35983106944434;17722;19571.608567828654;19626.411207663758;19632;20819;16648.58378540537;16670.416535421264;17255.74116388252;17375.565610859736;17432.38986635468;5795.275590551181;5882.941170588304;5932.2584676915385;5977.671228082454;5979.521638268928;5697.515711164556;5824.679642619675;5827.125931110325;5859.882802343951;5865.824025279248;5765.711714414283;5847.941520584804;5852.502537284315;5852.765889364442;5942.346341902387;5742.167385729067;5848.853778655568;5872.29532456108;5901.852453688649;5923.88152236955;5215.504527069922;5263.421023687391;5277.604179686539;5293.550048245866;5296.629235953485;23325.183618573268;24028.639570406456;24223.878880605662;24349.756502434928;24358.63462048071;2199.0763879170786;2563.128536297668;2570.6144078388206;3003.9549406758915;3197.088829683529;9267.907320926772;9774.364666296668;9995.950020249926;10309.278350515468;10319.84520232197;1948.7856335803192;1971.4874132725595;1989.2739150210214;2030.187924830065;2044.1721102953327;86509.56745216233;86921.69617455806;88133;90428;91363.54318228432;10870.456477176109;11116.388598627153;11217.60738374153;11297.096232301561;11610.129240306842;15822.287997040161;15855.68288634244;15882.364705411885;15930.442434514736;15936.362545498283;7569.810754731176;7844.450888437755;7902.960485197537;7945.960270198728;8069.193080691884;7286.744963926237;7378;7388.408927285805;7496.175420703718;7569.561783261162;4857.7085374876915;4902.7058376497525;4934.407871055496;4941.975290123526;4954.801807927714;1831.44141036985;1845.593969326746;1870.1397357215833;1907.6661584222707;1912.1777635616818;984.9261305402124;995.7311525887969;1036.404067661098;1057.7038429239826;1090.5092708281304;19356.70964935527;20653.17387304521;20768.37694869126;20798.37604871856;21037.47406314824;954.9140577347933;965.9613615455303;979.3264388220057;999.4952548962727;1011.4740335025892;11217.71955701114;11413.258138220988;11450.656480305437;11506;11580.420978951019;510014;514132;526638.9344426256;527900;547880;80102;101431;101787;103594.44608330881;107001.46499267453;989.8614194012844;994.2692121290845;1025.3796453145887;1031.7884833609091;1036.5594622285498;18016.2793488259;18227.635447290715;18678.53303667365;18704.158312876225;18930.337438189596;41801.372979404725;43893;44141.33787993118;44422.55577444184;44955;56607.15089273747;57055.71472142613;58684.4131558696;63785;67685.32314676988;8397.70608028716;8501.362397820312;8522.78693032679;8828.514431706319;8850.73447796567;13680.179189248278;13860.376283067488;14051.188536637848;14152.858471415155;14321.57035288943;12296.323702196465;12534.811977820522;12539.874601254236;12545.93727031359;12566.308853013172;11967.700807479883;12350.135490515577;12388.132830701765;12513.374331283218;12707.36463176856;12276.938615306866;12336.691582710146;12587.24476531411;12743.808842867178;12827.166234194747;8514.70198543048;8624.310055195694;8713.52075635846;8758.824823503366;8838;5852.209951656472;8900.06549312313;11119.555217791198;11689.298642081503;12137.332446715161 07:31:02 INFO - PROCESS | 3521 | |3;mootools.html;1458.4530800949644;1513.4929798517496;1669.9749503757446;1749.2041121289815;1816.173640993349;8724.38929274951;9237.399569028008;9296.721098367043;9321.72034838955;9322.161005509512;7853.685852565891;8046.557439340834;8323.33413326934;8584;9189.356745027833;2216.8226541876693;2249.8425110242206;2257.9887100564556;2259.9435014124615;2260.536589999046;2316.200910685819;2317.895694693743;2324.0471406723245;2328.1269523928527;2339.532093581282;1988.373662296372;2250.4148921280503;2316.2356422380576;2318.06118521998;2341.1454818991033;2294.1167650454695;2308.4921317310163;2330.697009388786;2333.0201315447466;2333.7782910623464;2294.4149241943196;2296.207808306132;2297.081167533;2298.0348253733305;2301.217586020761;9106;9177.816443671156;9208.217301529377;9214.308926830448;9216.769580760467;4945.653804233705;5118.539331460149;5151.922721159186;5152.819651312186;5166.7416629168765;3328.3509715605464;3350.782199157047;3473.2879759649213;3484;3488.7034602058857;1184.7452797648548;1191.8688944216128;2150.612819770054;2177.803997640204;2192.0683709423433;12859.935700321439;12978;12980.961523078131;13012.479500819956;13018.218906865619;4295.463067116611;4422.447194100738;4478.776061196927;4508.458984921765;4545.477270113913;9924.255680823968;9925.553350099262;9954.402735835729;9966.352187107816;9966.80066398668;5051.166557518042;5052.57053150479;5056.974715126401;5061.31672224245;5062.063518249136;4121.196366708523;4219.9578004220175;4237.660987121023;4438.889027774331;4707.270373092176;3791.7914514701406;3831.4061320495366;3847.5575308839284;3947.8420863165725;3957.762534247897;1139.3847322445952;1153.8557680289964;1165.9941700291445;1167.2237961755507;1180.3449085757316;1005.2209537608265;1007.8840933292619;1020.4438580973388;1025.6512785652765;1027.3168343051937;6168.352323006109;6188.81433556994;6370.649614271167;6490.675466226669;6551.8362040948405;450.6057199950044;454.77488643121865;458.1935793004336;463.3073555035244;476.914155452022;4373.453318335208;4429.955700443019;4482.75344856036;4485.506594274627;4486.955130448719;10981;11367.317960922372;11385.829212561819;11403.030742386823;11440.256383335223;11360.772784544262;11428.085753139729;11441.313521188755;11447.027002704697;11459.885401146048;8030.277275045274;8288.461250018729;8311.875321870177;8654.437461564978;8654.956725216334;6169.352218017044;6388.488920886411;6406;6434.453071488789;6436.485081193399;9790.510474476105;9912.653057142965;9973.700788976343;10068.546915388972;10227.232957528066;10106.494675266355;10505;10544.630937917136;10570.995755403224;10640.255182137176;7240.420766338575;7856.489328193649;8104.432689711663;8113.675452982051;8294;9046.909530904606;9047.954760226157;9076.591553380245;9080.863787043332;9121.042290559395;7428.368588670023;7730.961345193463;7777.338926191111;7851.764447066597;8108.148644392254;7668.386529077772;7712.691492340244;7764.340031097194;7875.881861772193;7971.3224375926375;7145.821354465972;7340;7376.0411146550605;7431.628418578941;7434;5263.973680131575;5285.180796976475;5394.406615272395;5469.863253418696;5530.806421775219;5439.422567455394;5443.346798384219;5451.781928722807;5517.503424691797;5562.582806289463 07:31:02 INFO - PROCESS | 3521 | |4;prototype.html;317.9618445786506;325.1594627886912;325.64504689888025;334.8660535785687;339.33829033384905;5364.195370694398;5379.9731001345035;5426.403095659479;5439.673619582818;5450;5336.519713225809;5360.65155764875;5363.275957745703;5363.720418822005;5371.361734670923;3959;4018.236535058344;4054.1688953764556;4095.5494895561615;4162.292410290258;4090.427340172378;4108.691848111389;4122.237386083569;4129.752214867102;4133.759872038391;3888.766673999569;4091.4272001919753;4096.836126554949;4102.917941641165;4106.445629839964;4054.128362402083;4164.12553363794;4164.833846522978;4166.958330416703;4167.624913757776;3662.633736626316;3670.889873303805;3678.098865777875;3679.4296883983025;3684.852605895761;5243.947560524384;5250.8687282817855;5263.421023687391;5273.9736301318635;5278.287431196778;4492.371068050443;4716.655753110347;4755.738434386108;4772.498887616785;4775.80896764129;5556.333240011184;5601;5602.159676048625;5605;5620.662760234399;4024.839006439768;4026.859059932888;4028.1470523559196;4034.3141665916864;4036.777977211252;5599.948028324573;5821.767129314865;5827.970860145672;5832.825015249549;5834.533237341003;3940.625640564142;3948.8350936473807;3950.20995800841;3966.147278335172;3971.6425521703186;4003.079291762911;4008.098177909947;4009.4587230723478;4018.2566225248424;4027.8993025174605;4534.478534968521;4594.908101837945;4613.930791038137;4618.122556714214;4664.903747619307;5590.888182236333;5597.468240517144;5614.578906582023;5619.353774315924;5641.407652196543;1388.902776805614;1407.5355132806149;1407.7466056109793;1408.8238970128734;1409.7391982483273;865.4028720183054;877.6752601537345;881.850085485469;890.3900827932887;938.9389989312034;5440.925876879551;5445.482679145475;5454.7818087276855;5458.45415458459;5465.496988328242;5642.689652069093;5664.348599910981;5671.546276297969;5675.177099320658;5685.720712839578;5173.896522069613;5429.755660995264;5455.699936503451;5469.56243500519;5475.370332411744;3039.650440199361;3043.6651968283472;3057.480228361184;3065.6781037991136;3069.6623371429123;5834.002103510717;5974.940250597525;5982.222311099529;5985.940140598538;6011.729472173763;5775.595708300462;5776.451237132465;5792.652440853562;5797.565182611321;5874.32445268791;5912;5922.555808314393;5936.257967754031;5944.791932282349;5945.702714864326;5411.592985823793;5584.636998595079;5621.072523033654;5665.943340566541;5680.6875621839545;5654.003378209555;5658.434156584309;5662.235598194356;5674.631148975303;5676.893005863817;5914.34942156371;5915.231019967377;5980.3421623622235;5985;6000.5799594028;3856;4062.959370406258;4069.552349241522;4075.144219713893;4087.21932212708;5556.249906262603;5633.126865335879;5748;5778.768849245983;5781.768729250783;5657.783576531066;5684.687342196219;5707.258056452634;5711.400302968129;5718.914216286676;5624.634398764068;5625.915611265752;5654.660720356627;5663.904355388532;5667.744951477276;5349.358077030781;5592.189132575672;5614.326280846324;5630.915536266877;5631.352394474688;5665.830025099253;5671.376148623731;5677.261955945701;5691.573132015198;5729.28383952006;5458.208559758892;5517.944820551743;5554.222408862682;5556.916646250229;5599.524040456607 07:31:02 INFO - PROCESS | 3521 | |5;yui.html;716.6130289643593;722.197872255648;727.9818004549886;738.3834498194008;764.5527366490601;13569.389377478012;14421.783673244883;14846.257687115629;14899.47851825185;14922.253887305646;12927.353632318394;13035.34823258838;13043.804342934864;13082;13261;664.9035889795977;785.0422484568817;791.2641243643409;807.0839597057353;818.7216346442209;818.2186012358184;820.4584973917216;822.6215940667271;822.62159406673;823.217942954193;857.6826574167535;859.669027424443;860.3504354212561;860.4536119564091;860.6299291304767;693.3794254142565;709.2481969112757;819.8442295963807;848.1052489623445;862.4351050062219;615.5875563372506;618.2920555963442;618.6999305336908;618.9133521306976;618.9690515474208;14046.1572305662;14064.718705625932;14078.436862525487;14088.943329250245;14096.718065638732;14694.118352898753;14928.178950157737;14993.625159371102;15023.248837558187;15079.170645614488;13114.540991065267;13190.208587484782;13193.076484646079;13334.799978000337;13381.866181338159;4403.2954727243805;4457.442819647544;4489.281714925596;4514.210013247702;4551.180787458256;76411;76772.61613691952;80566.59716701438;81425.59287203527;82239;11240.943795280971;11673.941630291794;11796.88203117975;11990.460429280703;12035.39823008846;22487.66268505974;22667.20664776725;22768.316950491513;22779.08883644669;22932.738699371363;8519.403641745019;8544.871826922601;8602.87095693565;8839.950217175065;9020.323475739346;4701.858944231679;4707.646926480527;4710.269908164171;4727.905441891212;4763.642726795504;3533.8056406897867;3634.890953271406;3639.6542328478754;3646.1249300168297;3647.6717095461536;551.9889602208013;605.9333473317931;613.4693490131004;642.5437939063224;643.9549231553747;691.6907665245549;706.1949377709416;731.8170457385654;816.4285000499674;818.6848063495478;9587.952060239793;10089.848652270222;10091.394516329045;10734.294613447933;10956.452177391098;664.2493981800534;687.2783577243874;726.6294189963157;731.9304666056717;779.9649015794303;4698.189683827251;4777.733900516366;4878.902421951612;4891.633127515451;4893.119238537098;113437.43281283705;115895;118383;122275.3886230581;123466.38266808788;97198.5140074295;97669;99778;100448;100983;85232.57383713165;88393;88447.49945446184;91074;92286;9422.29332800029;9622.759431014281;9679.560857675171;9682.419054856731;9714.902850971683;46539.30191047069;47027;48210;48661.27008094952;48738.99999999858;63971;65392;70283.29716702767;70429;71959;12324.691882703004;12605.30670813114;12629.494820207088;12834;13191.604251872459;13284.734305314032;13422.530211442552;13530.052896297164;13675.79486307726;13845.792313115513;10061.748456288651;12113.878861211275;14380.35288412045;14448.277586120863;14632.041502583925;14300.499482518062;14398.28008599545;14563.41746330135;14596.416143354147;14700.926495367456;14169.574912752634;14490.275486225857;14510.347034383267;14549.781753273921;14641.92679036598;11423.771524569629;11614.187006909435;12082.879171208528;12204.572839950559;12313.692157696129;11531.308121512737;12095.939520302694;12148.028157747181;12414.379281036092;12658.240505569695 07:31:02 INFO - PROCESS | 3521 | __end_tp_report 07:31:02 INFO - PROCESS | 3521 | __start_cc_report 07:31:02 INFO - PROCESS | 3521 | _x_x_mozilla_cycle_collect,808 07:31:02 INFO - PROCESS | 3521 | __end_cc_report 07:31:02 INFO - PROCESS | 3521 | __startTimestamp1468247462086__endTimestamp 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | ------- Summary: start ------- 07:31:02 INFO - PROCESS | 3521 | Number of tests: 6 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | [#0] dojo.html Cycles:165 Average:5302.25 Median:5486.29 stddev:597.22 (10.9%) stddev-sans-first:572.23 07:31:02 INFO - PROCESS | 3521 | Values: 3046.1 3055.6 3093.8 3099.4 3107.1 5196.5 5302.2 5310.9 5389.0 5421.7 5259.7 5295.6 5299.8 5313.8 5321.5 4783.9 4811.6 4869.6 4876.6 4976.7 4903.8 4904.4 4907.5 4956.4 4969.0 4767.2 4794.5 4796.0 4816.0 4839.6 4802.9 4821.3 4830.6 4885.5 4928.8 4841.8 4859.2 4861.9 4865.6 4866.1 5167.9 5182.5 5215.7 5241.9 5242.7 5595.1 5657.3 5685.5 5688.5 5688.8 5687.1 5717.0 5721.0 5736.9 5741.1 5221.8 5301.8 5374.4 5375.4 5454.3 5860.6 5888.4 5889.4 5890.3 5894.5 5394.0 5653.8 5661.7 5667.4 5670.5 5588.7 5634.7 5641.9 5722.2 5724.5 5652.1 5652.7 5654.9 5658.5 5700.5 5500.8 5608.7 5616.4 5650.0 5656.9 4392.8 4412.2 4419.4 4445.8 4451.5 3900.4 3923.9 3930.3 3934.2 3971.9 5248.7 5351.7 5398.1 5484.1 5536.4 5649.9 5726.7 5729.6 5736.7 5804.5 5444.9 5470.6 5472.7 5477.8 5478.0 4870.3 4901.1 4939.3 5000.0 5055.1 5975.9 5980.6 5987.8 5989.6 6027.5 5707.3 5713.9 5721.0 5722.1 5722.6 5788.2 5896.7 5943.0 5964.4 5966.9 5639.3 5640.7 5645.4 5646.3 5650.5 5674.3 5735.5 5736.9 5739.5 5741.9 5436.1 5548.7 5576.2 5603.2 5650.1 5599.9 5609.3 5612.4 5615.2 5624.9 5393.6 5486.3 5549.0 5550.9 5552.4 5516.7 5533.7 5563.8 5586.0 5638.3 5438.9 5443.6 5447.2 5447.5 5450.3 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | [#1] ext.html Cycles:180 Average:61242.04 Median:37748.81 stddev:121186.70 (321.0%) stddev-sans-first:121441.09 07:31:02 INFO - PROCESS | 3521 | Values: 588.9 606.5 612.8 616.3 620.2 35866.5 36503.3 43972.1 44484.0 44663.3 46105.5 46528.3 46588.8 46639.8 46660.3 9039.7 9191.1 9194.3 9199.7 9205.3 8172.6 10595.4 10836.7 10849.8 11249.6 11108.9 11185.5 11193.6 11204.8 11212.3 8118.8 10651.6 10981.3 11056.7 11061.3 10415.7 10817.6 10827.9 10830.9 10845.3 43897.8 43925.8 44093.1 44205.0 44233.6 3436.5 3642.8 3692.5 3692.7 3867.6 33556.8 33731.5 34059.7 34914.1 35213.1 4385.3 4471.0 4517.9 4540.8 4547.8 341531.0 345457.0 345465.0 345565.0 345994.0 61294.4 61548.0 61962.7 61965.1 62054.4 36438.6 36969.8 37036.3 38928.2 64059.4 15254.6 15326.2 15360.7 15392.1 15393.5 11613.3 11688.6 11693.9 11694.8 11695.7 19494.5 19599.2 19936.2 20007.8 20097.0 6774.7 6789.7 7014.4 7056.2 7164.0 815.7 837.7 846.7 988.1 1115.9 33665.2 34344.5 34510.3 34943.3 35053.0 1111.1 1139.8 1153.4 1197.6 1212.9 16988.6 17362.8 17427.7 17538.3 17645.2 567803.0 568510.0 570044.0 571315.0 572780.0 60974.0 61498.0 61554.0 61590.4 61597.0 1221.6 1228.0 1245.5 1345.6 1367.5 30961.7 34644.0 35040.6 35421.6 35657.6 51906.0 52238.5 52545.0 52553.2 52591.2 425794.0 426570.0 426757.0 427428.0 428915.0 28751.6 29077.1 29347.0 29461.0 30589.2 49704.8 49771.0 49945.5 51287.7 51313.2 41101.8 42518.8 42843.1 42960.1 43557.8 42316.0 42335.2 42797.8 43268.4 44069.3 42629.0 43382.8 43588.3 43754.6 43935.6 35210.6 37457.8 37663.6 37834.0 38613.8 28105.9 28216.2 28313.3 28344.0 28461.3 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | [#2] jquery.html Cycles:180 Average:30641.61 Median:1784.61 stddev:86770.64 (4862.2%) stddev-sans-first:86984.28 07:31:02 INFO - PROCESS | 3521 | Values: 372.1 375.2 380.6 389.5 401.4 17722.0 19571.6 19626.4 19632.0 20819.0 16648.6 16670.4 17255.7 17375.6 17432.4 5795.3 5882.9 5932.3 5977.7 5979.5 5697.5 5824.7 5827.1 5859.9 5865.8 5765.7 5847.9 5852.5 5852.8 5942.3 5742.2 5848.9 5872.3 5901.9 5923.9 5215.5 5263.4 5277.6 5293.6 5296.6 23325.2 24028.6 24223.9 24349.8 24358.6 2199.1 2563.1 2570.6 3004.0 3197.1 9267.9 9774.4 9996.0 10309.3 10319.8 1948.8 1971.5 1989.3 2030.2 2044.2 86509.6 86921.7 88133.0 90428.0 91363.5 10870.5 11116.4 11217.6 11297.1 11610.1 15822.3 15855.7 15882.4 15930.4 15936.4 7569.8 7844.5 7903.0 7946.0 8069.2 7286.7 7378.0 7388.4 7496.2 7569.6 4857.7 4902.7 4934.4 4942.0 4954.8 1831.4 1845.6 1870.1 1907.7 1912.2 984.9 995.7 1036.4 1057.7 1090.5 19356.7 20653.2 20768.4 20798.4 21037.5 954.9 966.0 979.3 999.5 1011.5 11217.7 11413.3 11450.7 11506.0 11580.4 510014.0 514132.0 526638.9 527900.0 547880.0 80102.0 101431.0 101787.0 103594.4 107001.5 989.9 994.3 1025.4 1031.8 1036.6 18016.3 18227.6 18678.5 18704.2 18930.3 41801.4 43893.0 44141.3 44422.6 44955.0 56607.2 57055.7 58684.4 63785.0 67685.3 8397.7 8501.4 8522.8 8828.5 8850.7 13680.2 13860.4 14051.2 14152.9 14321.6 12296.3 12534.8 12539.9 12545.9 12566.3 11967.7 12350.1 12388.1 12513.4 12707.4 12276.9 12336.7 12587.2 12743.8 12827.2 8514.7 8624.3 8713.5 8758.8 8838.0 5852.2 8900.1 11119.6 11689.3 12137.3 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | [#3] mootools.html Cycles:180 Average:5916.65 Median:2481.62 stddev:3415.48 (137.6%) stddev-sans-first:3408.63 07:31:02 INFO - PROCESS | 3521 | Values: 1458.5 1513.5 1670.0 1749.2 1816.2 8724.4 9237.4 9296.7 9321.7 9322.2 7853.7 8046.6 8323.3 8584.0 9189.4 2216.8 2249.8 2258.0 2259.9 2260.5 2316.2 2317.9 2324.0 2328.1 2339.5 1988.4 2250.4 2316.2 2318.1 2341.1 2294.1 2308.5 2330.7 2333.0 2333.8 2294.4 2296.2 2297.1 2298.0 2301.2 9106.0 9177.8 9208.2 9214.3 9216.8 4945.7 5118.5 5151.9 5152.8 5166.7 3328.4 3350.8 3473.3 3484.0 3488.7 1184.7 1191.9 2150.6 2177.8 2192.1 12859.9 12978.0 12981.0 13012.5 13018.2 4295.5 4422.4 4478.8 4508.5 4545.5 9924.3 9925.6 9954.4 9966.4 9966.8 5051.2 5052.6 5057.0 5061.3 5062.1 4121.2 4220.0 4237.7 4438.9 4707.3 3791.8 3831.4 3847.6 3947.8 3957.8 1139.4 1153.9 1166.0 1167.2 1180.3 1005.2 1007.9 1020.4 1025.7 1027.3 6168.4 6188.8 6370.6 6490.7 6551.8 450.6 454.8 458.2 463.3 476.9 4373.5 4430.0 4482.8 4485.5 4487.0 10981.0 11367.3 11385.8 11403.0 11440.3 11360.8 11428.1 11441.3 11447.0 11459.9 8030.3 8288.5 8311.9 8654.4 8655.0 6169.4 6388.5 6406.0 6434.5 6436.5 9790.5 9912.7 9973.7 10068.5 10227.2 10106.5 10505.0 10544.6 10571.0 10640.3 7240.4 7856.5 8104.4 8113.7 8294.0 9046.9 9048.0 9076.6 9080.9 9121.0 7428.4 7731.0 7777.3 7851.8 8108.1 7668.4 7712.7 7764.3 7875.9 7971.3 7145.8 7340.0 7376.0 7431.6 7434.0 5264.0 5285.2 5394.4 5469.9 5530.8 5439.4 5443.3 5451.8 5517.5 5562.6 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | [#4] prototype.html Cycles:180 Average:4704.36 Median:5452.39 stddev:1405.76 (25.8%) stddev-sans-first:1370.61 07:31:02 INFO - PROCESS | 3521 | Values: 318.0 325.2 325.6 334.9 339.3 5364.2 5380.0 5426.4 5439.7 5450.0 5336.5 5360.7 5363.3 5363.7 5371.4 3959.0 4018.2 4054.2 4095.5 4162.3 4090.4 4108.7 4122.2 4129.8 4133.8 3888.8 4091.4 4096.8 4102.9 4106.4 4054.1 4164.1 4164.8 4167.0 4167.6 3662.6 3670.9 3678.1 3679.4 3684.9 5243.9 5250.9 5263.4 5274.0 5278.3 4492.4 4716.7 4755.7 4772.5 4775.8 5556.3 5601.0 5602.2 5605.0 5620.7 4024.8 4026.9 4028.1 4034.3 4036.8 5599.9 5821.8 5828.0 5832.8 5834.5 3940.6 3948.8 3950.2 3966.1 3971.6 4003.1 4008.1 4009.5 4018.3 4027.9 4534.5 4594.9 4613.9 4618.1 4664.9 5590.9 5597.5 5614.6 5619.4 5641.4 1388.9 1407.5 1407.7 1408.8 1409.7 865.4 877.7 881.9 890.4 938.9 5440.9 5445.5 5454.8 5458.5 5465.5 5642.7 5664.3 5671.5 5675.2 5685.7 5173.9 5429.8 5455.7 5469.6 5475.4 3039.7 3043.7 3057.5 3065.7 3069.7 5834.0 5974.9 5982.2 5985.9 6011.7 5775.6 5776.5 5792.7 5797.6 5874.3 5912.0 5922.6 5936.3 5944.8 5945.7 5411.6 5584.6 5621.1 5665.9 5680.7 5654.0 5658.4 5662.2 5674.6 5676.9 5914.3 5915.2 5980.3 5985.0 6000.6 3856.0 4063.0 4069.6 4075.1 4087.2 5556.2 5633.1 5748.0 5778.8 5781.8 5657.8 5684.7 5707.3 5711.4 5718.9 5624.6 5625.9 5654.7 5663.9 5667.7 5349.4 5592.2 5614.3 5630.9 5631.4 5665.8 5671.4 5677.3 5691.6 5729.3 5458.2 5517.9 5554.2 5556.9 5599.5 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - PROCESS | 3521 | [#5] yui.html Cycles:180 Average:20902.37 Median:25545.24 stddev:30216.72 (118.3%) stddev-sans-first:30263.47 07:31:02 INFO - PROCESS | 3521 | Values: 716.6 722.2 728.0 738.4 764.6 13569.4 14421.8 14846.3 14899.5 14922.3 12927.4 13035.3 13043.8 13082.0 13261.0 664.9 785.0 791.3 807.1 818.7 818.2 820.5 822.6 822.6 823.2 857.7 859.7 860.4 860.5 860.6 693.4 709.2 819.8 848.1 862.4 615.6 618.3 618.7 618.9 619.0 14046.2 14064.7 14078.4 14088.9 14096.7 14694.1 14928.2 14993.6 15023.2 15079.2 13114.5 13190.2 13193.1 13334.8 13381.9 4403.3 4457.4 4489.3 4514.2 4551.2 76411.0 76772.6 80566.6 81425.6 82239.0 11240.9 11673.9 11796.9 11990.5 12035.4 22487.7 22667.2 22768.3 22779.1 22932.7 8519.4 8544.9 8602.9 8840.0 9020.3 4701.9 4707.6 4710.3 4727.9 4763.6 3533.8 3634.9 3639.7 3646.1 3647.7 552.0 605.9 613.5 642.5 644.0 691.7 706.2 731.8 816.4 818.7 9588.0 10089.8 10091.4 10734.3 10956.5 664.2 687.3 726.6 731.9 780.0 4698.2 4777.7 4878.9 4891.6 4893.1 113437.4 115895.0 118383.0 122275.4 123466.4 97198.5 97669.0 99778.0 100448.0 100983.0 85232.6 88393.0 88447.5 91074.0 92286.0 9422.3 9622.8 9679.6 9682.4 9714.9 46539.3 47027.0 48210.0 48661.3 48739.0 63971.0 65392.0 70283.3 70429.0 71959.0 12324.7 12605.3 12629.5 12834.0 13191.6 13284.7 13422.5 13530.1 13675.8 13845.8 10061.7 12113.9 14380.4 14448.3 14632.0 14300.5 14398.3 14563.4 14596.4 14700.9 14169.6 14490.3 14510.3 14549.8 14641.9 11423.8 11614.2 12082.9 12204.6 12313.7 11531.3 12095.9 12148.0 12414.4 12658.2 07:31:02 INFO - PROCESS | 3521 | -------- Summary: end -------- 07:31:02 INFO - PROCESS | 3521 | 07:31:02 INFO - TEST-INFO | 3521: exit 0 07:31:02 INFO - TEST-OK | dromaeo_css | took 1113461ms 07:31:02 INFO - TEST-START | kraken 07:31:02 INFO - operating with platform_type : linux_ 07:31:02 INFO - Initialising browser for kraken test... 07:31:02 INFO - TEST-INFO | started process 3724 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplxAaoQ/profile http://localhost:57364/getInfo.html) 07:31:06 INFO - PROCESS | 3724 | __metrics Screen width/height:1600/1200 07:31:06 INFO - PROCESS | 3724 | colorDepth:24 07:31:06 INFO - PROCESS | 3724 | Browser inner width/height: 1024/697 07:31:06 INFO - PROCESS | 3724 | __metrics 07:31:07 INFO - PROCESS | 3724 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:31:07 INFO - PROCESS | 3724 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:31:08 INFO - TEST-INFO | 3724: exit 0 07:31:08 INFO - Browser initialized. 07:31:08 INFO - Running cycle 1/1 for kraken test... 07:31:08 INFO - TEST-INFO | started process 3808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplxAaoQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:20 INFO - PROCESS | 3808 | RSS: Main: 157323264 07:31:20 INFO - PROCESS | 3808 | 07:31:23 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57364/tests/kraken/driver.html?testName=audio-beat-detection) 07:31:23 INFO - PROCESS | 3808 | RSS: Main: 216100864 07:31:23 INFO - PROCESS | 3808 | 07:31:26 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57364/tests/kraken/driver.html?testName=audio-dft) 07:31:27 INFO - PROCESS | 3808 | RSS: Main: 204132352 07:31:27 INFO - PROCESS | 3808 | 07:31:30 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57364/tests/kraken/driver.html?testName=audio-fft) 07:31:30 INFO - PROCESS | 3808 | RSS: Main: 269631488 07:31:30 INFO - PROCESS | 3808 | 07:31:32 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57364/tests/kraken/driver.html?testName=audio-oscillator) 07:31:33 INFO - PROCESS | 3808 | RSS: Main: 180219904 07:31:33 INFO - PROCESS | 3808 | 07:31:35 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57364/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:31:36 INFO - PROCESS | 3808 | RSS: Main: 184516608 07:31:36 INFO - PROCESS | 3808 | 07:31:39 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57364/tests/kraken/driver.html?testName=imaging-darkroom) 07:31:39 INFO - PROCESS | 3808 | RSS: Main: 219373568 07:31:39 INFO - PROCESS | 3808 | 07:31:43 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57364/tests/kraken/driver.html?testName=imaging-desaturate) 07:31:43 INFO - PROCESS | 3808 | RSS: Main: 220913664 07:31:43 INFO - PROCESS | 3808 | 07:31:46 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57364/tests/kraken/driver.html?testName=json-parse-financial) 07:31:47 INFO - PROCESS | 3808 | RSS: Main: 221523968 07:31:47 INFO - PROCESS | 3808 | 07:31:48 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57364/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:31:49 INFO - PROCESS | 3808 | RSS: Main: 209043456 07:31:49 INFO - PROCESS | 3808 | 07:31:51 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:31:51 INFO - PROCESS | 3808 | RSS: Main: 211955712 07:31:51 INFO - PROCESS | 3808 | 07:31:53 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:31:54 INFO - PROCESS | 3808 | RSS: Main: 210034688 07:31:54 INFO - PROCESS | 3808 | 07:31:57 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:31:57 INFO - PROCESS | 3808 | RSS: Main: 223748096 07:31:57 INFO - PROCESS | 3808 | 07:32:00 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:32:00 INFO - PROCESS | 3808 | RSS: Main: 225210368 07:32:00 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | Cycle 1(1): loaded http://localhost:57364/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57364/tests/kraken/driver.html?testName=ai-astar) 07:32:02 INFO - PROCESS | 3808 | RSS: Main: 267984896 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | __start_tp_report 07:32:02 INFO - PROCESS | 3808 | _x_x_mozilla_page_load 07:32:02 INFO - PROCESS | 3808 | _x_x_mozilla_page_load_details 07:32:02 INFO - PROCESS | 3808 | |i|pagename|runs| 07:32:02 INFO - PROCESS | 3808 | |0;ai-astar;101;111;111;109;112;113;112;111;110;111 07:32:02 INFO - PROCESS | 3808 | |1;audio-beat-detection;140;121;117;175;118;129;143;117;162;125 07:32:02 INFO - PROCESS | 3808 | |2;audio-dft;160;160;159;160;161;161;159;161;161;161 07:32:02 INFO - PROCESS | 3808 | |3;audio-fft;75;74;74;124;78;76;74;74;98;75 07:32:02 INFO - PROCESS | 3808 | |4;audio-oscillator;83;83;121;84;84;84;115;83;84;132 07:32:02 INFO - PROCESS | 3808 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;117;117;117 07:32:02 INFO - PROCESS | 3808 | |6;imaging-darkroom;145;146;145;164;145;145;146;145;145;146 07:32:02 INFO - PROCESS | 3808 | |7;imaging-desaturate;96;95;95;94;95;94;95;95;94;95 07:32:02 INFO - PROCESS | 3808 | |8;json-parse-financial;55;54;55;54;54;54;55;53;54;53 07:32:02 INFO - PROCESS | 3808 | |9;json-stringify-tinderbox;63;62;62;61;62;62;62;61;61;63 07:32:02 INFO - PROCESS | 3808 | |10;stanford-crypto-aes;75;75;76;80;75;74;75;76;74;83 07:32:02 INFO - PROCESS | 3808 | |11;stanford-crypto-ccm;123;116;130;125;118;119;136;119;198;122 07:32:02 INFO - PROCESS | 3808 | |12;stanford-crypto-pbkdf2;160;157;155;159;155;158;157;156;155;155 07:32:02 INFO - PROCESS | 3808 | |13;stanford-crypto-sha256-iterative;66;64;66;62;62;63;62;66;65;60 07:32:02 INFO - PROCESS | 3808 | __end_tp_report 07:32:02 INFO - PROCESS | 3808 | __start_cc_report 07:32:02 INFO - PROCESS | 3808 | _x_x_mozilla_cycle_collect,1640 07:32:02 INFO - PROCESS | 3808 | __end_cc_report 07:32:02 INFO - PROCESS | 3808 | __startTimestamp1468247522786__endTimestamp 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | ------- Summary: start ------- 07:32:02 INFO - PROCESS | 3808 | Number of tests: 14 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:3.38 (3.0%) stddev-sans-first:1.17 07:32:02 INFO - PROCESS | 3808 | Values: 101.0 111.0 111.0 109.0 112.0 113.0 112.0 111.0 110.0 111.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:134.50 stddev:20.24 (15.0%) stddev-sans-first:21.37 07:32:02 INFO - PROCESS | 3808 | Values: 140.0 121.0 117.0 175.0 118.0 129.0 143.0 117.0 162.0 125.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 07:32:02 INFO - PROCESS | 3808 | Values: 160.0 160.0 159.0 160.0 161.0 161.0 159.0 161.0 161.0 161.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.00 stddev:16.42 (21.9%) stddev-sans-first:17.20 07:32:02 INFO - PROCESS | 3808 | Values: 75.0 74.0 74.0 124.0 78.0 76.0 74.0 74.0 98.0 75.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:83.50 stddev:19.32 (23.1%) stddev-sans-first:19.97 07:32:02 INFO - PROCESS | 3808 | Values: 83.0 83.0 121.0 84.0 84.0 84.0 115.0 83.0 84.0 132.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:32:02 INFO - PROCESS | 3808 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:145.50 stddev:5.92 (4.1%) stddev-sans-first:6.23 07:32:02 INFO - PROCESS | 3808 | Values: 145.0 146.0 145.0 164.0 145.0 145.0 146.0 145.0 145.0 146.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 07:32:02 INFO - PROCESS | 3808 | Values: 96.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.74 (1.4%) stddev-sans-first:0.71 07:32:02 INFO - PROCESS | 3808 | Values: 55.0 54.0 55.0 54.0 54.0 54.0 55.0 53.0 54.0 53.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 07:32:02 INFO - PROCESS | 3808 | Values: 63.0 62.0 62.0 61.0 62.0 62.0 62.0 61.0 61.0 63.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:75.50 stddev:2.91 (3.9%) stddev-sans-first:3.05 07:32:02 INFO - PROCESS | 3808 | Values: 75.0 75.0 76.0 80.0 75.0 74.0 75.0 76.0 74.0 83.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#11] stanford-crypto-ccm Cycles:10 Average:130.60 Median:124.00 stddev:24.44 (19.7%) stddev-sans-first:25.77 07:32:02 INFO - PROCESS | 3808 | Values: 123.0 116.0 130.0 125.0 118.0 119.0 136.0 119.0 198.0 122.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.70 Median:157.00 stddev:1.83 (1.2%) stddev-sans-first:1.50 07:32:02 INFO - PROCESS | 3808 | Values: 160.0 157.0 155.0 159.0 155.0 158.0 157.0 156.0 155.0 155.0 07:32:02 INFO - PROCESS | 3808 | 07:32:02 INFO - PROCESS | 3808 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.50 stddev:2.12 (3.3%) stddev-sans-first:2.06 07:32:02 INFO - PROCESS | 3808 | Values: 66.0 64.0 66.0 62.0 62.0 63.0 62.0 66.0 65.0 60.0 07:32:02 INFO - PROCESS | 3808 | -------- Summary: end -------- 07:32:02 INFO - PROCESS | 3808 | 07:32:03 INFO - TEST-INFO | 3808: exit 0 07:32:03 INFO - TEST-OK | kraken | took 61027ms 07:32:03 INFO - SUITE-END | took 1174s 07:32:03 INFO - Completed test suite (00:19:34) 07:32:03 INFO - javascript benchmark 07:32:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3046.147078817929, 3055.572219889216, 3093.7679674024453, 3099.364630250798, 3107.1144723753737, 5196.480351964801, 5302.1781623848365, 5310.867228319294, 5389.029974604569, 5421.6746995180265, 5259.737013149346, 5295.602829787762, 5299.8410047698535, 5313.760880760366, 5321.494458026481, 4783.856484305467, 4811.639127065467, 4869.610431165498, 4876.609871210312, 4976.676516026465, 4903.803847846082, 4904.411470623512, 4907.484714105021, 4956.40523137224, 4969.0, 4767.237242041274, 4794.544518270741, 4796.040791841629, 4815.951840481585, 4839.588634966032, 4802.927956080662, 4821.252705830567, 4830.6135509159185, 4885.511448855122, 4928.827491037832, 4841.782119804618, 4859.222524396079, 4861.902761944777, 4865.56209941107, 4866.148424025781, 5167.948320516784, 5182.481751824825, 5215.713135777531, 5241.868953276198, 5242.659227150223, 5595.104783234663, 5657.3494048184575, 5685.54515638752, 5688.459596338341, 5688.800891968803, 5687.146927960797, 5716.971415142898, 5721.0, 5736.942630573682, 5741.081426971665, 5221.765020574083, 5301.787928482856, 5374.408815030343, 5375.354957405098, 5454.318210223722, 5860.648361098348, 5888.440598143169, 5889.440503152194, 5890.263717035371, 5894.498967587803, 5394.0, 5653.773849045992, 5661.716914154275, 5667.433256674382, 5670.489655930904, 5588.692621905834, 5634.690092044895, 5641.9435805641415, 5722.227499287626, 5724.513416359656, 5652.123920792284, 5652.660840349592, 5654.858628534237, 5658.519025882763, 5700.458456446631, 5500.80747173855, 5608.747606357723, 5616.410276920946, 5650.039493286151, 5656.85857853557, 4392.780360981938, 4412.24991751403, 4419.359192917009, 4445.799939002688, 4451.488078870907, 3900.414937759302, 3923.941140882889, 3930.253251882133, 3934.1541568562325, 3971.940420893689, 5248.737563121828, 5351.705656188869, 5398.1093119634825, 5484.122540393518, 5536.363318218457, 5649.943500565024, 5726.742296596663, 5729.627574207663, 5736.655800651891, 5804.535637149018, 5444.863878402993, 5470.56235501159, 5472.671639701631, 5477.808276710296, 5478.041342765, 4870.269459581091, 4901.142300097468, 4939.259111133359, 5000.049990501793, 5055.064813009605, 5975.910361344583, 5980.641161530322, 5987.760489580368, 5989.610675306091, 6027.487663548647, 5707.286589176353, 5713.885722285614, 5720.971395142998, 5722.055860783091, 5722.62802917809, 5788.189653448437, 5896.6756828374855, 5943.049112142036, 5964.4035596441745, 5966.910496342645, 5639.323281206118, 5640.717964101696, 5645.350784659817, 5646.2942132233475, 5650.519705825051, 5674.290713660793, 5735.4551317624755, 5736.942630573641, 5739.483446489836, 5741.91387129185, 5436.103042998019, 5548.722563871871, 5576.247206627054, 5603.159526070959, 5650.0677388232125, 5599.860003499945, 5609.326880774333, 5612.354579223442, 5615.18579805934, 5624.915626265692, 5393.568514518907, 5486.286782718221, 5549.0289199389135, 5550.86207327505, 5552.361478430033, 5516.696581687886, 5533.723313834212, 5563.833085007456, 5585.972070139624, 5638.323401191883, 5438.945610543844, 5443.618946673695, 5447.237386765935, 5447.455254474521, 5450.291462109901], "value": 5262.221618689292, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.9293284805824, 606.5352174498586, 612.8222815383542, 616.2758758458809, 620.1968450856141, 35866.462003069944, 36503.269934601325, 43972.12055758887, 44484.0, 44663.33005004928, 46105.53894461063, 46528.30207546889, 46588.767056164674, 46639.76680116603, 46660.3000954986, 9039.683611073613, 9191.126842949909, 9194.31042671799, 9199.678011269607, 9205.263578913671, 8172.591370431484, 10595.417252051135, 10836.674899752981, 10849.783004339908, 11249.60626378077, 11108.944455277713, 11185.49665265065, 11193.608223712172, 11204.831927521094, 11212.271202371861, 8118.75643730689, 10651.573937042509, 10981.286216395909, 11056.668299951014, 11061.336319820837, 10415.739606509822, 10817.567297308098, 10827.945860270727, 10830.891691083067, 10845.349279043283, 43897.78051109756, 43925.78037109794, 44093.118137637066, 44205.0, 44233.557664423264, 3436.518887355772, 3642.7996460194677, 3692.5015122958594, 3692.667659910621, 3867.57456679765, 33556.83221583877, 33731.49402758961, 34059.659403405894, 34914.127146821535, 35213.11967200815, 4385.298352263622, 4470.955290447087, 4517.932231016537, 4540.79566419512, 4547.795349209294, 341531.0, 345457.0, 345465.0, 345565.0, 345994.0, 61294.38705612887, 61548.0, 61962.690186549684, 61965.07052394218, 62054.37945620577, 36438.63561364405, 36969.81515092461, 37036.25927481489, 38928.22143557113, 64059.35940640534, 15254.618634534225, 15326.233688315762, 15360.692786144215, 15392.076475411512, 15393.538193854203, 11613.30320180792, 11688.590899318484, 11693.941530292293, 11694.824577631172, 11695.707607309885, 19494.512637184183, 19599.216031358872, 19936.20255189776, 20007.799922000595, 20097.0, 6774.661266936633, 6789.6605169741315, 7014.403775679022, 7056.188538318056, 7164.0, 815.7144999250215, 837.6733074100983, 846.6909578003985, 988.125508924607, 1115.9218854680094, 33665.15837104092, 34344.48483272753, 34510.30979380399, 34943.30113397718, 35053.0, 1111.1388673777888, 1139.8347239650204, 1153.4405813180601, 1197.6107764976418, 1212.860521040074, 16988.575285617957, 17362.826371736122, 17427.73858392125, 17538.298468061388, 17645.205965731573, 567803.0, 568510.0, 570044.0, 571315.0, 572780.0, 60974.0, 61498.0, 61554.0, 61590.384096158465, 61597.0, 1221.578555398404, 1228.0421271408316, 1245.495574292413, 1345.5694177863186, 1367.459853357918, 30961.690383096782, 34644.0, 35040.64959350374, 35421.64578354287, 35657.64342356543, 51906.0, 52238.47761522336, 52545.0, 52553.21170182374, 52591.21113183382, 425794.0, 426570.0, 426757.0, 427428.0, 428915.0, 28751.568726469537, 29077.12768616945, 29347.0, 29461.0, 30589.23526911845, 49704.75147624239, 49771.0, 49945.50054499409, 51287.743561283445, 51313.23030154476, 41101.79449102735, 42518.78740606277, 42843.143137137704, 42960.140797184504, 43557.78221108874, 42316.0, 42335.15329693327, 42797.790165737075, 43268.35097473604, 44069.33895991627, 42629.0, 43382.78308608437, 43588.34617480804, 43754.562454375045, 43935.56064439443, 35210.647893520734, 37457.81271093627, 37663.62336376711, 37834.0, 38613.80693096516, 28105.859470702515, 28216.22810525418, 28313.292167695974, 28344.0, 28461.288467788472], "value": 20640.198239510897, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.12179256953567, 375.249500998004, 380.61177598849173, 389.4664309895444, 401.35983106944434, 17722.0, 19571.608567828654, 19626.411207663758, 19632.0, 20819.0, 16648.58378540537, 16670.416535421264, 17255.74116388252, 17375.565610859736, 17432.38986635468, 5795.275590551181, 5882.941170588304, 5932.2584676915385, 5977.671228082454, 5979.521638268928, 5697.515711164556, 5824.679642619675, 5827.125931110325, 5859.882802343951, 5865.824025279248, 5765.711714414283, 5847.941520584804, 5852.502537284315, 5852.765889364442, 5942.346341902387, 5742.167385729067, 5848.853778655568, 5872.29532456108, 5901.852453688649, 5923.88152236955, 5215.504527069922, 5263.421023687391, 5277.604179686539, 5293.550048245866, 5296.629235953485, 23325.183618573268, 24028.639570406456, 24223.878880605662, 24349.756502434928, 24358.63462048071, 2199.0763879170786, 2563.128536297668, 2570.6144078388206, 3003.9549406758915, 3197.088829683529, 9267.907320926772, 9774.364666296668, 9995.950020249926, 10309.278350515468, 10319.84520232197, 1948.7856335803192, 1971.4874132725595, 1989.2739150210214, 2030.187924830065, 2044.1721102953327, 86509.56745216233, 86921.69617455806, 88133.0, 90428.0, 91363.54318228432, 10870.456477176109, 11116.388598627153, 11217.60738374153, 11297.096232301561, 11610.129240306842, 15822.287997040161, 15855.68288634244, 15882.364705411885, 15930.442434514736, 15936.362545498283, 7569.810754731176, 7844.450888437755, 7902.960485197537, 7945.960270198728, 8069.193080691884, 7286.744963926237, 7378.0, 7388.408927285805, 7496.175420703718, 7569.561783261162, 4857.7085374876915, 4902.7058376497525, 4934.407871055496, 4941.975290123526, 4954.801807927714, 1831.44141036985, 1845.593969326746, 1870.1397357215833, 1907.6661584222707, 1912.1777635616818, 984.9261305402124, 995.7311525887969, 1036.404067661098, 1057.7038429239826, 1090.5092708281304, 19356.70964935527, 20653.17387304521, 20768.37694869126, 20798.37604871856, 21037.47406314824, 954.9140577347933, 965.9613615455303, 979.3264388220057, 999.4952548962727, 1011.4740335025892, 11217.71955701114, 11413.258138220988, 11450.656480305437, 11506.0, 11580.420978951019, 510014.0, 514132.0, 526638.9344426256, 527900.0, 547880.0, 80102.0, 101431.0, 101787.0, 103594.44608330881, 107001.46499267453, 989.8614194012844, 994.2692121290845, 1025.3796453145887, 1031.7884833609091, 1036.5594622285498, 18016.2793488259, 18227.635447290715, 18678.53303667365, 18704.158312876225, 18930.337438189596, 41801.372979404725, 43893.0, 44141.33787993118, 44422.55577444184, 44955.0, 56607.15089273747, 57055.71472142613, 58684.4131558696, 63785.0, 67685.32314676988, 8397.70608028716, 8501.362397820312, 8522.78693032679, 8828.514431706319, 8850.73447796567, 13680.179189248278, 13860.376283067488, 14051.188536637848, 14152.858471415155, 14321.57035288943, 12296.323702196465, 12534.811977820522, 12539.874601254236, 12545.93727031359, 12566.308853013172, 11967.700807479883, 12350.135490515577, 12388.132830701765, 12513.374331283218, 12707.36463176856, 12276.938615306866, 12336.691582710146, 12587.24476531411, 12743.808842867178, 12827.166234194747, 8514.70198543048, 8624.310055195694, 8713.52075635846, 8758.824823503366, 8838.0, 5852.209951656472, 8900.06549312313, 11119.555217791198, 11689.298642081503, 12137.332446715161], "value": 9405.039070511708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.4530800949644, 1513.4929798517496, 1669.9749503757446, 1749.2041121289815, 1816.173640993349, 8724.38929274951, 9237.399569028008, 9296.721098367043, 9321.72034838955, 9322.161005509512, 7853.685852565891, 8046.557439340834, 8323.33413326934, 8584.0, 9189.356745027833, 2216.8226541876693, 2249.8425110242206, 2257.9887100564556, 2259.9435014124615, 2260.536589999046, 2316.200910685819, 2317.895694693743, 2324.0471406723245, 2328.1269523928527, 2339.532093581282, 1988.373662296372, 2250.4148921280503, 2316.2356422380576, 2318.06118521998, 2341.1454818991033, 2294.1167650454695, 2308.4921317310163, 2330.697009388786, 2333.0201315447466, 2333.7782910623464, 2294.4149241943196, 2296.207808306132, 2297.081167533, 2298.0348253733305, 2301.217586020761, 9106.0, 9177.816443671156, 9208.217301529377, 9214.308926830448, 9216.769580760467, 4945.653804233705, 5118.539331460149, 5151.922721159186, 5152.819651312186, 5166.7416629168765, 3328.3509715605464, 3350.782199157047, 3473.2879759649213, 3484.0, 3488.7034602058857, 1184.7452797648548, 1191.8688944216128, 2150.612819770054, 2177.803997640204, 2192.0683709423433, 12859.935700321439, 12978.0, 12980.961523078131, 13012.479500819956, 13018.218906865619, 4295.463067116611, 4422.447194100738, 4478.776061196927, 4508.458984921765, 4545.477270113913, 9924.255680823968, 9925.553350099262, 9954.402735835729, 9966.352187107816, 9966.80066398668, 5051.166557518042, 5052.57053150479, 5056.974715126401, 5061.31672224245, 5062.063518249136, 4121.196366708523, 4219.9578004220175, 4237.660987121023, 4438.889027774331, 4707.270373092176, 3791.7914514701406, 3831.4061320495366, 3847.5575308839284, 3947.8420863165725, 3957.762534247897, 1139.3847322445952, 1153.8557680289964, 1165.9941700291445, 1167.2237961755507, 1180.3449085757316, 1005.2209537608265, 1007.8840933292619, 1020.4438580973388, 1025.6512785652765, 1027.3168343051937, 6168.352323006109, 6188.81433556994, 6370.649614271167, 6490.675466226669, 6551.8362040948405, 450.6057199950044, 454.77488643121865, 458.1935793004336, 463.3073555035244, 476.914155452022, 4373.453318335208, 4429.955700443019, 4482.75344856036, 4485.506594274627, 4486.955130448719, 10981.0, 11367.317960922372, 11385.829212561819, 11403.030742386823, 11440.256383335223, 11360.772784544262, 11428.085753139729, 11441.313521188755, 11447.027002704697, 11459.885401146048, 8030.277275045274, 8288.461250018729, 8311.875321870177, 8654.437461564978, 8654.956725216334, 6169.352218017044, 6388.488920886411, 6406.0, 6434.453071488789, 6436.485081193399, 9790.510474476105, 9912.653057142965, 9973.700788976343, 10068.546915388972, 10227.232957528066, 10106.494675266355, 10505.0, 10544.630937917136, 10570.995755403224, 10640.255182137176, 7240.420766338575, 7856.489328193649, 8104.432689711663, 8113.675452982051, 8294.0, 9046.909530904606, 9047.954760226157, 9076.591553380245, 9080.863787043332, 9121.042290559395, 7428.368588670023, 7730.961345193463, 7777.338926191111, 7851.764447066597, 8108.148644392254, 7668.386529077772, 7712.691492340244, 7764.340031097194, 7875.881861772193, 7971.3224375926375, 7145.821354465972, 7340.0, 7376.0411146550605, 7431.628418578941, 7434.0, 5263.973680131575, 5285.180796976475, 5394.406615272395, 5469.863253418696, 5530.806421775219, 5439.422567455394, 5443.346798384219, 5451.781928722807, 5517.503424691797, 5562.582806289463], "value": 4661.797134269002, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.9618445786506, 325.1594627886912, 325.64504689888025, 334.8660535785687, 339.33829033384905, 5364.195370694398, 5379.9731001345035, 5426.403095659479, 5439.673619582818, 5450.0, 5336.519713225809, 5360.65155764875, 5363.275957745703, 5363.720418822005, 5371.361734670923, 3959.0, 4018.236535058344, 4054.1688953764556, 4095.5494895561615, 4162.292410290258, 4090.427340172378, 4108.691848111389, 4122.237386083569, 4129.752214867102, 4133.759872038391, 3888.766673999569, 4091.4272001919753, 4096.836126554949, 4102.917941641165, 4106.445629839964, 4054.128362402083, 4164.12553363794, 4164.833846522978, 4166.958330416703, 4167.624913757776, 3662.633736626316, 3670.889873303805, 3678.098865777875, 3679.4296883983025, 3684.852605895761, 5243.947560524384, 5250.8687282817855, 5263.421023687391, 5273.9736301318635, 5278.287431196778, 4492.371068050443, 4716.655753110347, 4755.738434386108, 4772.498887616785, 4775.80896764129, 5556.333240011184, 5601.0, 5602.159676048625, 5605.0, 5620.662760234399, 4024.839006439768, 4026.859059932888, 4028.1470523559196, 4034.3141665916864, 4036.777977211252, 5599.948028324573, 5821.767129314865, 5827.970860145672, 5832.825015249549, 5834.533237341003, 3940.625640564142, 3948.8350936473807, 3950.20995800841, 3966.147278335172, 3971.6425521703186, 4003.079291762911, 4008.098177909947, 4009.4587230723478, 4018.2566225248424, 4027.8993025174605, 4534.478534968521, 4594.908101837945, 4613.930791038137, 4618.122556714214, 4664.903747619307, 5590.888182236333, 5597.468240517144, 5614.578906582023, 5619.353774315924, 5641.407652196543, 1388.902776805614, 1407.5355132806149, 1407.7466056109793, 1408.8238970128734, 1409.7391982483273, 865.4028720183054, 877.6752601537345, 881.850085485469, 890.3900827932887, 938.9389989312034, 5440.925876879551, 5445.482679145475, 5454.7818087276855, 5458.45415458459, 5465.496988328242, 5642.689652069093, 5664.348599910981, 5671.546276297969, 5675.177099320658, 5685.720712839578, 5173.896522069613, 5429.755660995264, 5455.699936503451, 5469.56243500519, 5475.370332411744, 3039.650440199361, 3043.6651968283472, 3057.480228361184, 3065.6781037991136, 3069.6623371429123, 5834.002103510717, 5974.940250597525, 5982.222311099529, 5985.940140598538, 6011.729472173763, 5775.595708300462, 5776.451237132465, 5792.652440853562, 5797.565182611321, 5874.32445268791, 5912.0, 5922.555808314393, 5936.257967754031, 5944.791932282349, 5945.702714864326, 5411.592985823793, 5584.636998595079, 5621.072523033654, 5665.943340566541, 5680.6875621839545, 5654.003378209555, 5658.434156584309, 5662.235598194356, 5674.631148975303, 5676.893005863817, 5914.34942156371, 5915.231019967377, 5980.3421623622235, 5985.0, 6000.5799594028, 3856.0, 4062.959370406258, 4069.552349241522, 4075.144219713893, 4087.21932212708, 5556.249906262603, 5633.126865335879, 5748.0, 5778.768849245983, 5781.768729250783, 5657.783576531066, 5684.687342196219, 5707.258056452634, 5711.400302968129, 5718.914216286676, 5624.634398764068, 5625.915611265752, 5654.660720356627, 5663.904355388532, 5667.744951477276, 5349.358077030781, 5592.189132575672, 5614.326280846324, 5630.915536266877, 5631.352394474688, 5665.830025099253, 5671.376148623731, 5677.261955945701, 5691.573132015198, 5729.28383952006, 5458.208559758892, 5517.944820551743, 5554.222408862682, 5556.916646250229, 5599.524040456607], "value": 4249.833244321188, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.6130289643593, 722.197872255648, 727.9818004549886, 738.3834498194008, 764.5527366490601, 13569.389377478012, 14421.783673244883, 14846.257687115629, 14899.47851825185, 14922.253887305646, 12927.353632318394, 13035.34823258838, 13043.804342934864, 13082.0, 13261.0, 664.9035889795977, 785.0422484568817, 791.2641243643409, 807.0839597057353, 818.7216346442209, 818.2186012358184, 820.4584973917216, 822.6215940667271, 822.62159406673, 823.217942954193, 857.6826574167535, 859.669027424443, 860.3504354212561, 860.4536119564091, 860.6299291304767, 693.3794254142565, 709.2481969112757, 819.8442295963807, 848.1052489623445, 862.4351050062219, 615.5875563372506, 618.2920555963442, 618.6999305336908, 618.9133521306976, 618.9690515474208, 14046.1572305662, 14064.718705625932, 14078.436862525487, 14088.943329250245, 14096.718065638732, 14694.118352898753, 14928.178950157737, 14993.625159371102, 15023.248837558187, 15079.170645614488, 13114.540991065267, 13190.208587484782, 13193.076484646079, 13334.799978000337, 13381.866181338159, 4403.2954727243805, 4457.442819647544, 4489.281714925596, 4514.210013247702, 4551.180787458256, 76411.0, 76772.61613691952, 80566.59716701438, 81425.59287203527, 82239.0, 11240.943795280971, 11673.941630291794, 11796.88203117975, 11990.460429280703, 12035.39823008846, 22487.66268505974, 22667.20664776725, 22768.316950491513, 22779.08883644669, 22932.738699371363, 8519.403641745019, 8544.871826922601, 8602.87095693565, 8839.950217175065, 9020.323475739346, 4701.858944231679, 4707.646926480527, 4710.269908164171, 4727.905441891212, 4763.642726795504, 3533.8056406897867, 3634.890953271406, 3639.6542328478754, 3646.1249300168297, 3647.6717095461536, 551.9889602208013, 605.9333473317931, 613.4693490131004, 642.5437939063224, 643.9549231553747, 691.6907665245549, 706.1949377709416, 731.8170457385654, 816.4285000499674, 818.6848063495478, 9587.952060239793, 10089.848652270222, 10091.394516329045, 10734.294613447933, 10956.452177391098, 664.2493981800534, 687.2783577243874, 726.6294189963157, 731.9304666056717, 779.9649015794303, 4698.189683827251, 4777.733900516366, 4878.902421951612, 4891.633127515451, 4893.119238537098, 113437.43281283705, 115895.0, 118383.0, 122275.3886230581, 123466.38266808788, 97198.5140074295, 97669.0, 99778.0, 100448.0, 100983.0, 85232.57383713165, 88393.0, 88447.49945446184, 91074.0, 92286.0, 9422.29332800029, 9622.759431014281, 9679.560857675171, 9682.419054856731, 9714.902850971683, 46539.30191047069, 47027.0, 48210.0, 48661.27008094952, 48738.99999999858, 63971.0, 65392.0, 70283.29716702767, 70429.0, 71959.0, 12324.691882703004, 12605.30670813114, 12629.494820207088, 12834.0, 13191.604251872459, 13284.734305314032, 13422.530211442552, 13530.052896297164, 13675.79486307726, 13845.792313115513, 10061.748456288651, 12113.878861211275, 14380.35288412045, 14448.277586120863, 14632.041502583925, 14300.499482518062, 14398.28008599545, 14563.41746330135, 14596.416143354147, 14700.926495367456, 14169.574912752634, 14490.275486225857, 14510.347034383267, 14549.781753273921, 14641.92679036598, 11423.771524569629, 11614.187006909435, 12082.879171208528, 12204.572839950559, 12313.692157696129, 11531.308121512737, 12095.939520302694, 12148.028157747181, 12414.379281036092, 12658.240505569695], "value": 7615.889041840488, "unit": "score"}], "name": "dromaeo_css", "value": 7322.442226231741, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 111.0, 109.0, 112.0, 113.0, 112.0, 111.0, 110.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 121.0, 117.0, 175.0, 118.0, 129.0, 143.0, 117.0, 162.0, 125.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 160.0, 161.0, 161.0, 159.0, 161.0, 161.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 124.0, 78.0, 76.0, 74.0, 74.0, 98.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 121.0, 84.0, 84.0, 84.0, 115.0, 83.0, 84.0, 132.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 164.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 54.0, 54.0, 55.0, 53.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 61.0, 61.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 80.0, 75.0, 74.0, 75.0, 76.0, 74.0, 83.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 116.0, 130.0, 125.0, 118.0, 119.0, 136.0, 119.0, 198.0, 122.0], "value": 130.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 157.0, 155.0, 159.0, 155.0, 158.0, 157.0, 156.0, 155.0, 155.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 62.0, 62.0, 63.0, 62.0, 66.0, 65.0, 60.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1484.8999999999999, "alertThreshold": 2.0}]} 07:32:04 INFO - Return code: 0 07:32:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:32:04 INFO - # TBPL SUCCESS # 07:32:04 INFO - Running post-action listener: _resource_record_post_action 07:32:04 INFO - ##### 07:32:04 INFO - ##### Finished run-tests step (success) 07:32:04 INFO - ##### 07:32:04 INFO - Running post-run listener: _resource_record_post_run 07:32:04 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 553140224; Read time: 0; Write time: 865904 07:32:04 INFO - TinderboxPrint: CPU usage
13.0% 07:32:04 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 07:32:04 INFO - TinderboxPrint: I/O write bytes / time
553,140,224 / 865,904 07:32:04 INFO - TinderboxPrint: CPU idle
8,274.3 (87.0%) 07:32:04 INFO - TinderboxPrint: CPU user
1,192.9 (12.5%) 07:32:04 INFO - TinderboxPrint: Swap in / out
0 / 0 07:32:04 INFO - install - Wall time: 18s; CPU: 15.0%; Read bytes: 0; Write bytes: 256823296; Read time: 0; Write time: 503896 07:32:04 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 296198144; Read time: 0; Write time: 361976 07:32:04 INFO - Running post-run listener: _upload_blobber_files 07:32:04 INFO - Blob upload gear active. 07:32:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:32:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:32:05 INFO - (blobuploader) - INFO - Open directory for files ... 07:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:32:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:05 INFO - (blobuploader) - INFO - Done attempting. 07:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:32:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:08 INFO - (blobuploader) - INFO - Done attempting. 07:32:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:32:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:32:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:09 INFO - (blobuploader) - INFO - Done attempting. 07:32:09 INFO - (blobuploader) - INFO - Iteration through files over. 07:32:09 INFO - Return code: 0 07:32:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:32:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:32:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260784d75969699bb042b743dad15cc1a2367b1f33e12ae27135ed08d750338d58bae204356e33a04b01853ef271b1e31fbb3b71916a88ee54d9766a44783fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0939fdd065eda162a1dd224be542d07d1f35bb31aab15f5e6ebb6fdfeeb6ce69923b3d15e7e8c66bf2b8d9c47102407a5f57a87dde98a2405a5e3f018aa9b81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1df511f05a0aabb03a8ec343c5148555ec33d08cf2805558c27be760d3cb679ee500e16179d4daf993b5a47c2e59a445c8deae74076ce443fe478a26ee4c9bc1"} 07:32:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:32:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:32:09 INFO - Contents: 07:32:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260784d75969699bb042b743dad15cc1a2367b1f33e12ae27135ed08d750338d58bae204356e33a04b01853ef271b1e31fbb3b71916a88ee54d9766a44783fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0939fdd065eda162a1dd224be542d07d1f35bb31aab15f5e6ebb6fdfeeb6ce69923b3d15e7e8c66bf2b8d9c47102407a5f57a87dde98a2405a5e3f018aa9b81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1df511f05a0aabb03a8ec343c5148555ec33d08cf2805558c27be760d3cb679ee500e16179d4daf993b5a47c2e59a445c8deae74076ce443fe478a26ee4c9bc1"} 07:32:09 INFO - Running post-run listener: copy_logs_to_upload_dir 07:32:09 INFO - Copying logs to upload dir... 07:32:09 INFO - mkdir: /builds/slave/test/build/upload/logs 07:32:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.735622 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-07-11 07:32:09.266215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 07:32:09.269686) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260784d75969699bb042b743dad15cc1a2367b1f33e12ae27135ed08d750338d58bae204356e33a04b01853ef271b1e31fbb3b71916a88ee54d9766a44783fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0939fdd065eda162a1dd224be542d07d1f35bb31aab15f5e6ebb6fdfeeb6ce69923b3d15e7e8c66bf2b8d9c47102407a5f57a87dde98a2405a5e3f018aa9b81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1df511f05a0aabb03a8ec343c5148555ec33d08cf2805558c27be760d3cb679ee500e16179d4daf993b5a47c2e59a445c8deae74076ce443fe478a26ee4c9bc1"} build_url:https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011609 build_url: 'https://queue.taskcluster.net/v1/task/V7L1dlgiRl6yXDHa-0-zOg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260784d75969699bb042b743dad15cc1a2367b1f33e12ae27135ed08d750338d58bae204356e33a04b01853ef271b1e31fbb3b71916a88ee54d9766a44783fa6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0939fdd065eda162a1dd224be542d07d1f35bb31aab15f5e6ebb6fdfeeb6ce69923b3d15e7e8c66bf2b8d9c47102407a5f57a87dde98a2405a5e3f018aa9b81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1df511f05a0aabb03a8ec343c5148555ec33d08cf2805558c27be760d3cb679ee500e16179d4daf993b5a47c2e59a445c8deae74076ce443fe478a26ee4c9bc1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 07:32:09.295394) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:32:09.295724) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=05b19d94dfa9f3ce7b6e62cc000001f7-1468246180.709049-1952192224 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004583 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 07:32:09.348010) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 07:32:09.348348) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 07:32:09.348649) ========= ========= Total master_lag: 0.14 =========