builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-010 starttime: 1466681493.35 results: success (0) buildid: 20160623035525 builduid: ab02ecc92e85482fbee1de2b03e20318 revision: 8ec0039f5a778f6afe8f6e063ea56cf887e55e7d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.352387) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.352905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.353315) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.368765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.369094) ========= 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=eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-23 04:31:33-- 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% 45.9M=0s 2016-06-23 04:31:33 (45.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.193545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.578134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.578679) ========= 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=eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018131 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:33.629137) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 04:31:33.629928) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8ec0039f5a778f6afe8f6e063ea56cf887e55e7d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8ec0039f5a778f6afe8f6e063ea56cf887e55e7d --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=eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-23 04:31:33,881 truncating revision to first 12 chars 2016-06-23 04:31:33,881 Setting DEBUG logging. 2016-06-23 04:31:33,882 attempt 1/10 2016-06-23 04:31:33,882 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8ec0039f5a77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-23 04:31:35,326 unpacking tar archive at: fx-team-8ec0039f5a77/testing/mozharness/ program finished with exit code 0 elapsedTime=1.897758 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-23 04:31:35.544533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:35.545001) ========= script_repo_revision: 8ec0039f5a778f6afe8f6e063ea56cf887e55e7d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:35.545688) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:35.546301) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 04:31:35.556129) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-23 04:31:35.556424) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:31:35 INFO - MultiFileLogger online at 20160623 04:31:35 in /builds/slave/test 04:31:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 04:31:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:31:35 INFO - {'append_to_log': False, 04:31:35 INFO - 'base_work_dir': '/builds/slave/test', 04:31:35 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:31:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:31:35 INFO - 'branch': 'Fx-Team-Non-PGO', 04:31:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:31:35 INFO - 'config_files': ('talos/linux_config.py',), 04:31:35 INFO - 'default_actions': ('clobber', 04:31:35 INFO - 'read-buildbot-config', 04:31:35 INFO - 'download-and-extract', 04:31:35 INFO - 'populate-webroot', 04:31:35 INFO - 'create-virtualenv', 04:31:35 INFO - 'install', 04:31:35 INFO - 'run-tests'), 04:31:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:31:35 INFO - 'download_minidump_stackwalk': True, 04:31:35 INFO - 'download_symbols': 'ondemand', 04:31:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:31:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:31:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:31:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:31:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:31:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:31:35 INFO - 'installer_path': 'installer.exe', 04:31:35 INFO - 'log_level': 'info', 04:31:35 INFO - 'log_name': 'talos', 04:31:35 INFO - 'log_to_console': True, 04:31:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:31:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:31:35 INFO - 'opt_config_files': (), 04:31:35 INFO - 'pip_index': False, 04:31:35 INFO - 'sps_profile': False, 04:31:35 INFO - 'sps_profile_interval': 0, 04:31:35 INFO - 'suite': 'dromaeojs-e10s', 04:31:35 INFO - 'system_bits': '32', 04:31:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:31:35 INFO - 'title': 'talos-linux64-ix-010', 04:31:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:31:35 INFO - 'use_talos_json': True, 04:31:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:31:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:31:35 INFO - 'work_dir': 'build'} 04:31:35 INFO - ##### 04:31:35 INFO - ##### Running clobber step. 04:31:35 INFO - ##### 04:31:35 INFO - Running pre-action listener: _resource_record_pre_action 04:31:35 INFO - Running main action method: clobber 04:31:35 INFO - rmtree: /builds/slave/test/build 04:31:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:31:36 INFO - Running post-action listener: _resource_record_post_action 04:31:36 INFO - ##### 04:31:36 INFO - ##### Finished clobber step (success) 04:31:36 INFO - ##### 04:31:36 INFO - ##### 04:31:36 INFO - ##### Running read-buildbot-config step. 04:31:36 INFO - ##### 04:31:36 INFO - Running pre-action listener: _resource_record_pre_action 04:31:36 INFO - Running main action method: read_buildbot_config 04:31:36 INFO - Using buildbot properties: 04:31:36 INFO - { 04:31:36 INFO - "project": "", 04:31:36 INFO - "product": "firefox", 04:31:36 INFO - "script_repo_revision": "production", 04:31:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 04:31:36 INFO - "repository": "", 04:31:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 04:31:36 INFO - "buildid": "20160623035525", 04:31:36 INFO - "pgo_build": "False", 04:31:36 INFO - "basedir": "/builds/slave/test", 04:31:36 INFO - "buildnumber": 1385, 04:31:36 INFO - "slavename": "talos-linux64-ix-010", 04:31:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:31:36 INFO - "platform": "ubuntu64_hw", 04:31:36 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:31:36 INFO - "branch": "fx-team", 04:31:36 INFO - "repo_path": "integration/fx-team", 04:31:36 INFO - "revision": "8ec0039f5a778f6afe8f6e063ea56cf887e55e7d", 04:31:36 INFO - "stage_platform": "linux64", 04:31:36 INFO - "builduid": "ab02ecc92e85482fbee1de2b03e20318", 04:31:36 INFO - "slavebuilddir": "test" 04:31:36 INFO - } 04:31:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:31:36 INFO - Running post-action listener: _resource_record_post_action 04:31:36 INFO - ##### 04:31:36 INFO - ##### Finished read-buildbot-config step (success) 04:31:36 INFO - ##### 04:31:36 INFO - ##### 04:31:36 INFO - ##### Running download-and-extract step. 04:31:36 INFO - ##### 04:31:36 INFO - Running pre-action listener: _resource_record_pre_action 04:31:36 INFO - Running main action method: download_and_extract 04:31:36 INFO - mkdir: /builds/slave/test/build/tests 04:31:36 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')]} 04:31:36 INFO - https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:31:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:31:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:31:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:37 INFO - Downloaded 1361 bytes. 04:31:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:31:37 INFO - Using the following test package requirements: 04:31:37 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:31:37 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:31:37 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:31:37 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'jsshell-linux-x86_64.zip'], 04:31:37 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:31:37 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:31:37 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:31:37 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:31:37 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:31:37 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:31:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:31:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:31:37 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')]} 04:31:37 INFO - https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:31:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:31:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:31:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:38 INFO - Downloaded 22709579 bytes. 04:31:38 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'] 04:31:38 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 04:31:39 INFO - Return code: 0 04:31:39 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 04:31:39 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')]} 04:31:39 INFO - https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:31:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:31:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:31:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:41 INFO - Downloaded 22709579 bytes. 04:31:41 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'] 04:31:41 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 04:31:42 INFO - Return code: 0 04:31:42 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')]} 04:31:42 INFO - https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:31:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:31:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:31:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:43 INFO - Downloaded 14405384 bytes. 04:31:43 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'] 04:31:43 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 04:31:43 INFO - Return code: 0 04:31:43 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')]} 04:31:43 INFO - https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:31:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:31:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:31:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AuDmYaVHRxyGcyLMy9dE4A/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 04:31:45 INFO - Downloaded 56247793 bytes. 04:31:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:31:45 INFO - mkdir: /builds/slave/test/properties 04:31:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:31:45 INFO - Writing to file /builds/slave/test/properties/build_url 04:31:45 INFO - Contents: 04:31:45 INFO - build_url:https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:31:45 INFO - Running post-action listener: _resource_record_post_action 04:31:45 INFO - Running post-action listener: set_extra_try_arguments 04:31:45 INFO - ##### 04:31:45 INFO - ##### Finished download-and-extract step (success) 04:31:45 INFO - ##### 04:31:45 INFO - ##### 04:31:45 INFO - ##### Running populate-webroot step. 04:31:45 INFO - ##### 04:31:45 INFO - Running pre-action listener: _resource_record_pre_action 04:31:45 INFO - Running main action method: populate_webroot 04:31:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:31:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:31:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:31:45 INFO - '--tppagecycles', 04:31:45 INFO - '7'], 04:31:45 INFO - 'tests': ['tsvgm']}}, 04:31:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:31:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:31:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:31:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:31:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:31:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['damp', 'tps']}, 04:31:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'tests': ['damp', 'tps']}, 04:31:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['dromaeo_dom']}, 04:31:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:31:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['basic_compositor_video']}, 04:31:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:31:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['a11yr', 04:31:45 INFO - 'ts_paint', 04:31:45 INFO - 'tpaint', 04:31:45 INFO - 'sessionrestore', 04:31:45 INFO - 'sessionrestore_no_auto_restore', 04:31:45 INFO - 'tabpaint']}, 04:31:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:31:45 INFO - 'ts_paint', 04:31:45 INFO - 'tpaint', 04:31:45 INFO - 'sessionrestore', 04:31:45 INFO - 'sessionrestore_no_auto_restore', 04:31:45 INFO - 'tabpaint']}, 04:31:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:31:45 INFO - 'ts_paint', 04:31:45 INFO - 'tpaint', 04:31:45 INFO - 'sessionrestore', 04:31:45 INFO - 'sessionrestore_no_auto_restore', 04:31:45 INFO - 'tabpaint']}, 04:31:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:31:45 INFO - 'ts_paint', 04:31:45 INFO - 'tpaint', 04:31:45 INFO - 'sessionrestore', 04:31:45 INFO - 'sessionrestore_no_auto_restore', 04:31:45 INFO - 'tabpaint']}, 04:31:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['a11yr', 04:31:45 INFO - 'ts_paint', 04:31:45 INFO - 'tpaint', 04:31:45 INFO - 'sessionrestore', 04:31:45 INFO - 'sessionrestore_no_auto_restore', 04:31:45 INFO - 'tabpaint']}, 04:31:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['a11yr', 04:31:45 INFO - 'ts_paint', 04:31:45 INFO - 'tpaint', 04:31:45 INFO - 'sessionrestore', 04:31:45 INFO - 'sessionrestore_no_auto_restore', 04:31:45 INFO - 'tabpaint']}, 04:31:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['tsvgx', 04:31:45 INFO - 'tsvgr_opacity', 04:31:45 INFO - 'tart', 04:31:45 INFO - 'tscrollx', 04:31:45 INFO - 'cart']}, 04:31:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:31:45 INFO - 'tsvgr_opacity', 04:31:45 INFO - 'tart', 04:31:45 INFO - 'tscrollx', 04:31:45 INFO - 'cart']}, 04:31:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'talos_options': ['--disable-e10s'], 04:31:45 INFO - 'tests': ['tp5o']}, 04:31:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'tests': ['tp5o']}, 04:31:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'talos_options': ['--disable-e10s', 04:31:45 INFO - '--xperf_path', 04:31:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:45 INFO - 'tests': ['tp5n']}, 04:31:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:31:45 INFO - 'talos_options': ['--xperf_path', 04:31:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:31:45 INFO - 'tests': ['tp5n']}}, 04:31:45 INFO - 'talos.zip': {'path': '', 04:31:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:31:45 INFO - Running post-action listener: _resource_record_post_action 04:31:45 INFO - ##### 04:31:45 INFO - ##### Finished populate-webroot step (success) 04:31:45 INFO - ##### 04:31:45 INFO - ##### 04:31:45 INFO - ##### Running create-virtualenv step. 04:31:45 INFO - ##### 04:31:45 INFO - Running pre-action listener: _resource_record_pre_action 04:31:45 INFO - Running main action method: create_virtualenv 04:31:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:31:45 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 04:31:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:31:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:31:46 INFO - Using real prefix '/usr' 04:31:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:31:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:31:48 INFO - Installing pip.................done. 04:31:48 INFO - Return code: 0 04:31:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:31:48 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')]} 04:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:48 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')]} 04:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:48 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:31:48 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 04:31:48 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 04:31:48 INFO - Using env: {'DISPLAY': ':0', 04:31:48 INFO - 'HOME': '/home/cltbld', 04:31:48 INFO - 'LANG': 'en_US.UTF-8', 04:31:48 INFO - 'LANGUAGE': 'en_US:en', 04:31:48 INFO - 'LOGNAME': 'cltbld', 04:31:48 INFO - 'MAIL': '/var/mail/cltbld', 04:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:48 INFO - 'MOZ_NO_REMOTE': '1', 04:31:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:48 INFO - 'NO_EM_RESTART': '1', 04:31:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:48 INFO - 'PWD': '/builds/slave/test', 04:31:48 INFO - 'SHELL': '/bin/bash', 04:31:48 INFO - 'SHLVL': '1', 04:31:48 INFO - 'TERM': 'linux', 04:31:48 INFO - 'TMOUT': '86400', 04:31:48 INFO - 'USER': 'cltbld', 04:31:48 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:48 INFO - '_': '/tools/buildbot/bin/python'} 04:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:48 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 04:31:48 INFO - Cleaning up... 04:31:48 INFO - Return code: 0 04:31:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:31:48 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')]} 04:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:48 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')]} 04:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:48 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:31:48 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 04:31:48 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 04:31:48 INFO - Using env: {'DISPLAY': ':0', 04:31:48 INFO - 'HOME': '/home/cltbld', 04:31:48 INFO - 'LANG': 'en_US.UTF-8', 04:31:48 INFO - 'LANGUAGE': 'en_US:en', 04:31:48 INFO - 'LOGNAME': 'cltbld', 04:31:48 INFO - 'MAIL': '/var/mail/cltbld', 04:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:48 INFO - 'MOZ_NO_REMOTE': '1', 04:31:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:48 INFO - 'NO_EM_RESTART': '1', 04:31:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:48 INFO - 'PWD': '/builds/slave/test', 04:31:48 INFO - 'SHELL': '/bin/bash', 04:31:48 INFO - 'SHLVL': '1', 04:31:48 INFO - 'TERM': 'linux', 04:31:48 INFO - 'TMOUT': '86400', 04:31:48 INFO - 'USER': 'cltbld', 04:31:48 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:48 INFO - '_': '/tools/buildbot/bin/python'} 04:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:48 INFO - Downloading/unpacking psutil>=3.1.1 04:31:48 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 04:31:48 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 04:31:48 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 04:31:48 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 04:31:48 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 04:31:48 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 04:31:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:31:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:31:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:31:50 INFO - Installing collected packages: psutil 04:31:50 INFO - Running setup.py install for psutil 04:31:50 INFO - building 'psutil._psutil_linux' extension 04:31:50 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 04:31:51 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 04:31:51 INFO - building 'psutil._psutil_posix' extension 04:31:51 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 04:31:51 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 04:31:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:31:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:31:51 INFO - Successfully installed psutil 04:31:51 INFO - Cleaning up... 04:31:51 INFO - Return code: 0 04:31:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:31:51 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')]} 04:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:51 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')]} 04:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:51 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:31:51 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 04:31:51 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 04:31:51 INFO - Using env: {'DISPLAY': ':0', 04:31:51 INFO - 'HOME': '/home/cltbld', 04:31:51 INFO - 'LANG': 'en_US.UTF-8', 04:31:51 INFO - 'LANGUAGE': 'en_US:en', 04:31:51 INFO - 'LOGNAME': 'cltbld', 04:31:51 INFO - 'MAIL': '/var/mail/cltbld', 04:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:51 INFO - 'MOZ_NO_REMOTE': '1', 04:31:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:51 INFO - 'NO_EM_RESTART': '1', 04:31:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:51 INFO - 'PWD': '/builds/slave/test', 04:31:51 INFO - 'SHELL': '/bin/bash', 04:31:51 INFO - 'SHLVL': '1', 04:31:51 INFO - 'TERM': 'linux', 04:31:51 INFO - 'TMOUT': '86400', 04:31:51 INFO - 'USER': 'cltbld', 04:31:51 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:51 INFO - '_': '/tools/buildbot/bin/python'} 04:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:31:51 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 04:31:51 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 04:31:51 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 04:31:51 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 04:31:51 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 04:31:51 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 04:31:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:31:53 INFO - Installing collected packages: mozsystemmonitor 04:31:53 INFO - Running setup.py install for mozsystemmonitor 04:31:53 INFO - Successfully installed mozsystemmonitor 04:31:53 INFO - Cleaning up... 04:31:53 INFO - Return code: 0 04:31:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:31:53 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')]} 04:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:53 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')]} 04:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:53 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:31:53 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 04:31:53 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 04:31:53 INFO - Using env: {'DISPLAY': ':0', 04:31:53 INFO - 'HOME': '/home/cltbld', 04:31:53 INFO - 'LANG': 'en_US.UTF-8', 04:31:53 INFO - 'LANGUAGE': 'en_US:en', 04:31:53 INFO - 'LOGNAME': 'cltbld', 04:31:53 INFO - 'MAIL': '/var/mail/cltbld', 04:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:53 INFO - 'MOZ_NO_REMOTE': '1', 04:31:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:53 INFO - 'NO_EM_RESTART': '1', 04:31:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:53 INFO - 'PWD': '/builds/slave/test', 04:31:53 INFO - 'SHELL': '/bin/bash', 04:31:53 INFO - 'SHLVL': '1', 04:31:53 INFO - 'TERM': 'linux', 04:31:53 INFO - 'TMOUT': '86400', 04:31:53 INFO - 'USER': 'cltbld', 04:31:53 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:53 INFO - '_': '/tools/buildbot/bin/python'} 04:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:53 INFO - Downloading/unpacking jsonschema==2.5.1 04:31:53 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 04:31:53 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 04:31:53 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 04:31:53 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 04:31:53 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 04:31:53 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 04:31:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:31:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:31: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 04:31: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 04:31: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 04:31: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 04:31: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 04:31: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 04:31:56 INFO - Downloading functools32-3.2.3-2.tar.gz 04:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:31:56 INFO - warning: no files found matching '*.txt' 04:31:56 INFO - no previously-included directories found matching 'build' 04:31:56 INFO - no previously-included directories found matching 'dist' 04:31:56 INFO - no previously-included directories found matching '.git*' 04:31:56 INFO - Installing collected packages: jsonschema, functools32 04:31:56 INFO - Running setup.py install for functools32 04:31:56 INFO - warning: no files found matching '*.txt' 04:31:56 INFO - no previously-included directories found matching 'build' 04:31:56 INFO - no previously-included directories found matching 'dist' 04:31:56 INFO - no previously-included directories found matching '.git*' 04:31:56 INFO - Successfully installed jsonschema functools32 04:31:56 INFO - Cleaning up... 04:31:56 INFO - Return code: 0 04:31:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:31:56 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')]} 04:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:56 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')]} 04:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:56 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:31:56 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 04:31:56 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 04:31:56 INFO - Using env: {'DISPLAY': ':0', 04:31:56 INFO - 'HOME': '/home/cltbld', 04:31:56 INFO - 'LANG': 'en_US.UTF-8', 04:31:56 INFO - 'LANGUAGE': 'en_US:en', 04:31:56 INFO - 'LOGNAME': 'cltbld', 04:31:56 INFO - 'MAIL': '/var/mail/cltbld', 04:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:56 INFO - 'MOZ_NO_REMOTE': '1', 04:31:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:56 INFO - 'NO_EM_RESTART': '1', 04:31:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:56 INFO - 'PWD': '/builds/slave/test', 04:31:56 INFO - 'SHELL': '/bin/bash', 04:31:56 INFO - 'SHLVL': '1', 04:31:56 INFO - 'TERM': 'linux', 04:31:56 INFO - 'TMOUT': '86400', 04:31:56 INFO - 'USER': 'cltbld', 04:31:56 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:56 INFO - '_': '/tools/buildbot/bin/python'} 04:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:31:56 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 04:31:56 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 04:31:56 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 04:31:56 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 04:31:56 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 04:31:56 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 04:31:58 INFO - Downloading blobuploader-1.2.4.tar.gz 04:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:31:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:31:58 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 04:31:58 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 04:31:58 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 04:31:58 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 04:31:58 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 04:31:58 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 04:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:31:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:31:58 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 04:31:58 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 04:31:58 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 04:31:58 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 04:31:58 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 04:31:58 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 04:31:59 INFO - Downloading docopt-0.6.1.tar.gz 04:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:31:59 INFO - Installing collected packages: blobuploader, requests, docopt 04:31:59 INFO - Running setup.py install for blobuploader 04:31:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:31:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:31:59 INFO - Running setup.py install for requests 04:31:59 INFO - Running setup.py install for docopt 04:31:59 INFO - Successfully installed blobuploader requests docopt 04:31:59 INFO - Cleaning up... 04:31:59 INFO - Return code: 0 04:31:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:59 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')]} 04:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:59 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')]} 04:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:59 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:31:59 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 04:31:59 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 04:31:59 INFO - Using env: {'DISPLAY': ':0', 04:31:59 INFO - 'HOME': '/home/cltbld', 04:31:59 INFO - 'LANG': 'en_US.UTF-8', 04:31:59 INFO - 'LANGUAGE': 'en_US:en', 04:31:59 INFO - 'LOGNAME': 'cltbld', 04:31:59 INFO - 'MAIL': '/var/mail/cltbld', 04:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:59 INFO - 'MOZ_NO_REMOTE': '1', 04:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:59 INFO - 'NO_EM_RESTART': '1', 04:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:59 INFO - 'PWD': '/builds/slave/test', 04:31:59 INFO - 'SHELL': '/bin/bash', 04:31:59 INFO - 'SHLVL': '1', 04:31:59 INFO - 'TERM': 'linux', 04:31:59 INFO - 'TMOUT': '86400', 04:31:59 INFO - 'USER': 'cltbld', 04:31:59 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:59 INFO - '_': '/tools/buildbot/bin/python'} 04:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:59 INFO - Running setup.py (path:/tmp/pip-ESArTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:00 INFO - Running setup.py (path:/tmp/pip-ItXb0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:00 INFO - Running setup.py (path:/tmp/pip-WZywlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:00 INFO - Running setup.py (path:/tmp/pip-LtzE67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:00 INFO - Running setup.py (path:/tmp/pip-iV777e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:00 INFO - Running setup.py (path:/tmp/pip-4g2NpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:00 INFO - Running setup.py (path:/tmp/pip-OsoWiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:00 INFO - Running setup.py (path:/tmp/pip-d8P22n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:00 INFO - Running setup.py (path:/tmp/pip-8t9PCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:00 INFO - Running setup.py (path:/tmp/pip-bLxanz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:00 INFO - Running setup.py (path:/tmp/pip-FyLpeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:00 INFO - Running setup.py (path:/tmp/pip-OJyTvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:00 INFO - Running setup.py (path:/tmp/pip-rGkm6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:00 INFO - Running setup.py (path:/tmp/pip-0t_e1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:00 INFO - Running setup.py (path:/tmp/pip-Yd4RLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:01 INFO - Running setup.py (path:/tmp/pip-LgLvDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:01 INFO - Running setup.py (path:/tmp/pip-p_hwai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:32:01 INFO - Running setup.py install for manifestparser 04:32:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Running setup.py install for mozcrash 04:32:01 INFO - Running setup.py install for mozdebug 04:32:01 INFO - Running setup.py install for mozdevice 04:32:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Running setup.py install for mozfile 04:32:01 INFO - Running setup.py install for mozhttpd 04:32:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Running setup.py install for mozinfo 04:32:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Running setup.py install for mozInstall 04:32:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:32:01 INFO - Running setup.py install for mozleak 04:32:02 INFO - Running setup.py install for mozlog 04:32:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:32:02 INFO - Running setup.py install for moznetwork 04:32:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:32:02 INFO - Running setup.py install for mozprocess 04:32:02 INFO - Running setup.py install for mozprofile 04:32:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:32:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:32:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:32:02 INFO - Running setup.py install for mozrunner 04:32:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:32:02 INFO - Running setup.py install for mozscreenshot 04:32:02 INFO - Running setup.py install for moztest 04:32:02 INFO - Running setup.py install for mozversion 04:32:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:32:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:32:02 INFO - Cleaning up... 04:32:02 INFO - Return code: 0 04:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:02 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')]} 04:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:02 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')]} 04:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:02 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:32:02 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 04:32:02 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 04:32:02 INFO - Using env: {'DISPLAY': ':0', 04:32:02 INFO - 'HOME': '/home/cltbld', 04:32:02 INFO - 'LANG': 'en_US.UTF-8', 04:32:02 INFO - 'LANGUAGE': 'en_US:en', 04:32:02 INFO - 'LOGNAME': 'cltbld', 04:32:02 INFO - 'MAIL': '/var/mail/cltbld', 04:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:02 INFO - 'MOZ_NO_REMOTE': '1', 04:32:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:02 INFO - 'NO_EM_RESTART': '1', 04:32:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:02 INFO - 'PWD': '/builds/slave/test', 04:32:02 INFO - 'SHELL': '/bin/bash', 04:32:02 INFO - 'SHLVL': '1', 04:32:02 INFO - 'TERM': 'linux', 04:32:02 INFO - 'TMOUT': '86400', 04:32:02 INFO - 'USER': 'cltbld', 04:32:02 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:02 INFO - '_': '/tools/buildbot/bin/python'} 04:32:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:03 INFO - Running setup.py (path:/tmp/pip-ry5x2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:03 INFO - Running setup.py (path:/tmp/pip-jTy6ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:03 INFO - Running setup.py (path:/tmp/pip-WCi1xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:03 INFO - Running setup.py (path:/tmp/pip-aZ6XFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:03 INFO - Running setup.py (path:/tmp/pip-t4uKDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:03 INFO - Running setup.py (path:/tmp/pip-W4BVaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:03 INFO - Running setup.py (path:/tmp/pip-lVfLxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:03 INFO - Running setup.py (path:/tmp/pip-YjPvbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:03 INFO - Running setup.py (path:/tmp/pip-zYQCJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:03 INFO - Running setup.py (path:/tmp/pip-sXOMfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:03 INFO - Running setup.py (path:/tmp/pip-qa9_jA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:03 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)) 04:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:03 INFO - Running setup.py (path:/tmp/pip-0CmlHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 04:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:04 INFO - Running setup.py (path:/tmp/pip-Vq6Ry2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:04 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)) 04:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:04 INFO - Running setup.py (path:/tmp/pip-Y8a3_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 04:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:04 INFO - Running setup.py (path:/tmp/pip-kjWJuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:04 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)) 04:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:04 INFO - Running setup.py (path:/tmp/pip-RmNBek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:04 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)) 04:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:04 INFO - Running setup.py (path:/tmp/pip-rcSimC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:04 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)) 04:32:04 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)) 04:32:04 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)) 04:32:04 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)) 04:32:04 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)) 04:32:04 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)) 04:32:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:32:04 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 04:32:04 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 04:32:04 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 04:32:04 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 04:32:04 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 04:32:04 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 04:32:06 INFO - Downloading blessings-1.6.tar.gz 04:32:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:32:06 INFO - Installing collected packages: blessings 04:32:06 INFO - Running setup.py install for blessings 04:32:06 INFO - Successfully installed blessings 04:32:06 INFO - Cleaning up... 04:32:06 INFO - Return code: 0 04:32:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:06 INFO - Reading from file tmpfile_stdout 04:32:06 INFO - Current package versions: 04:32:06 INFO - argparse == 1.2.1 04:32:06 INFO - blessings == 1.6 04:32:06 INFO - blobuploader == 1.2.4 04:32:06 INFO - docopt == 0.6.1 04:32:06 INFO - functools32 == 3.2.3-2 04:32:06 INFO - jsonschema == 2.5.1 04:32:06 INFO - manifestparser == 1.1 04:32:06 INFO - mozInstall == 1.12 04:32:06 INFO - mozcrash == 0.17 04:32:06 INFO - mozdebug == 0.1 04:32:06 INFO - mozdevice == 0.48 04:32:06 INFO - mozfile == 1.2 04:32:06 INFO - mozhttpd == 0.7 04:32:06 INFO - mozinfo == 0.9 04:32:06 INFO - mozleak == 0.1 04:32:06 INFO - mozlog == 3.2 04:32:06 INFO - moznetwork == 0.27 04:32:06 INFO - mozprocess == 0.22 04:32:06 INFO - mozprofile == 0.28 04:32:06 INFO - mozrunner == 6.11 04:32:06 INFO - mozscreenshot == 0.1 04:32:06 INFO - mozsystemmonitor == 0.3 04:32:06 INFO - moztest == 0.7 04:32:06 INFO - mozversion == 1.4 04:32:06 INFO - psutil == 3.1.1 04:32:06 INFO - requests == 1.2.3 04:32:06 INFO - wsgiref == 0.1.2 04:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:06 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')]} 04:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:06 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')]} 04:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:06 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:32:06 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 04:32:06 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 04:32:06 INFO - Using env: {'DISPLAY': ':0', 04:32:06 INFO - 'HOME': '/home/cltbld', 04:32:06 INFO - 'LANG': 'en_US.UTF-8', 04:32:06 INFO - 'LANGUAGE': 'en_US:en', 04:32:06 INFO - 'LOGNAME': 'cltbld', 04:32:06 INFO - 'MAIL': '/var/mail/cltbld', 04:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:06 INFO - 'MOZ_NO_REMOTE': '1', 04:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:06 INFO - 'NO_EM_RESTART': '1', 04:32:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:06 INFO - 'PWD': '/builds/slave/test', 04:32:06 INFO - 'SHELL': '/bin/bash', 04:32:06 INFO - 'SHLVL': '1', 04:32:06 INFO - 'TERM': 'linux', 04:32:06 INFO - 'TMOUT': '86400', 04:32:06 INFO - 'USER': 'cltbld', 04:32:06 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:06 INFO - '_': '/tools/buildbot/bin/python'} 04:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 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)) 04:32:06 INFO - Cleaning up... 04:32:07 INFO - Return code: 0 04:32:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:07 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 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:32:07 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 04:32:07 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 04:32:07 INFO - Using env: {'DISPLAY': ':0', 04:32:07 INFO - 'HOME': '/home/cltbld', 04:32:07 INFO - 'LANG': 'en_US.UTF-8', 04:32:07 INFO - 'LANGUAGE': 'en_US:en', 04:32:07 INFO - 'LOGNAME': 'cltbld', 04:32:07 INFO - 'MAIL': '/var/mail/cltbld', 04:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:07 INFO - 'MOZ_NO_REMOTE': '1', 04:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:07 INFO - 'NO_EM_RESTART': '1', 04:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:07 INFO - 'PWD': '/builds/slave/test', 04:32:07 INFO - 'SHELL': '/bin/bash', 04:32:07 INFO - 'SHLVL': '1', 04:32:07 INFO - 'TERM': 'linux', 04:32:07 INFO - 'TMOUT': '86400', 04:32:07 INFO - 'USER': 'cltbld', 04:32:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:07 INFO - '_': '/tools/buildbot/bin/python'} 04:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:32:07 INFO - Cleaning up... 04:32:07 INFO - Return code: 0 04:32:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:07 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f057352af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2047020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, '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': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', '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 04:32:07 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', 'flake8'] in /builds/slave/test/build 04:32:07 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 flake8 04:32:07 INFO - Using env: {'DISPLAY': ':0', 04:32:07 INFO - 'HOME': '/home/cltbld', 04:32:07 INFO - 'LANG': 'en_US.UTF-8', 04:32:07 INFO - 'LANGUAGE': 'en_US:en', 04:32:07 INFO - 'LOGNAME': 'cltbld', 04:32:07 INFO - 'MAIL': '/var/mail/cltbld', 04:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:07 INFO - 'MOZ_NO_REMOTE': '1', 04:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:07 INFO - 'NO_EM_RESTART': '1', 04:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:07 INFO - 'PWD': '/builds/slave/test', 04:32:07 INFO - 'SHELL': '/bin/bash', 04:32:07 INFO - 'SHLVL': '1', 04:32:07 INFO - 'TERM': 'linux', 04:32:07 INFO - 'TMOUT': '86400', 04:32:07 INFO - 'USER': 'cltbld', 04:32:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:07 INFO - '_': '/tools/buildbot/bin/python'} 04:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:07 INFO - Downloading/unpacking flake8 04:32:07 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 04:32:07 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 04:32:07 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 04:32:07 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 04:32:07 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 04:32:07 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 04:32:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:32:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:32:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 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 04:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:32:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:32:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:32:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:32:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:32:10 INFO - no previously-included directories found matching 'docs/_build' 04:32:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:32:10 INFO - Running setup.py install for pep8 04:32:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:32:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:32:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:32:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:32:10 INFO - no previously-included directories found matching 'docs/_build' 04:32:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:32:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:32:10 INFO - Cleaning up... 04:32:10 INFO - Return code: 0 04:32:10 INFO - Running post-action listener: _resource_record_post_action 04:32:10 INFO - Running post-action listener: _start_resource_monitoring 04:32:10 INFO - Starting resource monitoring. 04:32:10 INFO - ##### 04:32:10 INFO - ##### Finished create-virtualenv step (success) 04:32:10 INFO - ##### 04:32:10 INFO - ##### 04:32:10 INFO - ##### Running install step. 04:32:10 INFO - ##### 04:32:10 INFO - Running pre-action listener: _resource_record_pre_action 04:32:10 INFO - Running main action method: install 04:32:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:10 INFO - Reading from file tmpfile_stdout 04:32:10 INFO - Detecting whether we're running mozinstall >=1.0... 04:32:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:32:10 INFO - Reading from file tmpfile_stdout 04:32:10 INFO - Output received: 04:32:10 INFO - Usage: mozinstall [options] installer 04:32:10 INFO - Options: 04:32:10 INFO - -h, --help show this help message and exit 04:32:10 INFO - -d DEST, --destination=DEST 04:32:10 INFO - Directory to install application into. [default: 04:32:10 INFO - "/builds/slave/test"] 04:32:10 INFO - --app=APP Application being installed. [default: firefox] 04:32:10 INFO - mkdir: /builds/slave/test/build/application 04:32:10 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'] 04:32:10 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 04:32:26 INFO - Reading from file tmpfile_stdout 04:32:26 INFO - Output received: 04:32:26 INFO - /builds/slave/test/build/application/firefox/firefox 04:32:26 INFO - Running post-action listener: _resource_record_post_action 04:32:26 INFO - ##### 04:32:26 INFO - ##### Finished install step (success) 04:32:26 INFO - ##### 04:32:26 INFO - ##### 04:32:26 INFO - ##### Running run-tests step. 04:32:26 INFO - ##### 04:32:26 INFO - Running pre-action listener: _resource_record_pre_action 04:32:26 INFO - Running main action method: run_tests 04:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:32:26 INFO - Python 2.7.3 04:32:26 INFO - Return code: 0 04:32:26 INFO - grabbing minidump binary from tooltool 04:32:26 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')]} 04:32:26 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 0x20d92e0>, '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 0x2045140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20455d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:32:26 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 04:32:26 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 04:32:26 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 04:32:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:32:26 INFO - Return code: 0 04:32:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:32:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:32:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:32:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:32:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:32:27 INFO - Return code: 0 04:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:32:27 INFO - Using env: {'DISPLAY': ':0', 04:32:27 INFO - 'HOME': '/home/cltbld', 04:32:27 INFO - 'LANG': 'en_US.UTF-8', 04:32:27 INFO - 'LANGUAGE': 'en_US:en', 04:32:27 INFO - 'LOGNAME': 'cltbld', 04:32:27 INFO - 'MAIL': '/var/mail/cltbld', 04:32:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:32:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:27 INFO - 'MOZ_NO_REMOTE': '1', 04:32:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:32:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:27 INFO - 'NO_EM_RESTART': '1', 04:32:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:27 INFO - 'PWD': '/builds/slave/test', 04:32:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:32:27 INFO - 'SHELL': '/bin/bash', 04:32:27 INFO - 'SHLVL': '1', 04:32:27 INFO - 'TERM': 'linux', 04:32:27 INFO - 'TMOUT': '86400', 04:32:27 INFO - 'USER': 'cltbld', 04:32:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930', 04:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:27 INFO - '_': '/tools/buildbot/bin/python'} 04:32:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:32:28 INFO - mozversion application_buildid: 20160623035525 04:32:28 INFO - mozversion application_changeset: 8ec0039f5a778f6afe8f6e063ea56cf887e55e7d 04:32:28 INFO - mozversion application_display_name: Nightly 04:32:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:32:28 INFO - mozversion application_name: Firefox 04:32:28 INFO - mozversion application_remotingname: firefox 04:32:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:32:28 INFO - mozversion application_vendor: Mozilla 04:32:28 INFO - mozversion application_version: 50.0a1 04:32:28 INFO - mozversion platform_buildid: 20160623035525 04:32:28 INFO - mozversion platform_changeset: 8ec0039f5a778f6afe8f6e063ea56cf887e55e7d 04:32:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:32:28 INFO - mozversion platform_version: 50.0a1 04:32:28 INFO - using testdate: 1466681548 04:32:28 INFO - actual date: 1466681548 04:32:28 INFO - starting webserver on 'localhost:38762' 04:32:28 INFO - SUITE-START | Running 2 tests 04:32:28 INFO - TEST-START | dromaeo_css 04:32:28 INFO - operating with platform_type : linux_ 04:32:28 INFO - Initialising browser for dromaeo_css test... 04:32:28 INFO - TEST-INFO | started process 22609 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkeMBpu/profile http://localhost:38762/getInfo.html) 04:32:33 INFO - PROCESS | 22609 | __metrics Screen width/height:1600/1200 04:32:33 INFO - PROCESS | 22609 | colorDepth:24 04:32:33 INFO - PROCESS | 22609 | Browser inner width/height: 1024/697 04:32:33 INFO - PROCESS | 22609 | __metrics 04:32:34 ERROR - PROCESS | 22609 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 04:32:34 INFO - TEST-INFO | 22609: exit 0 04:32:34 INFO - Browser initialized. 04:32:34 INFO - Running cycle 1/1 for dromaeo_css test... 04:32:34 INFO - TEST-INFO | started process 22727 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkeMBpu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:47 INFO - PROCESS | 22727 | RSS: Main: 186073088 04:32:47 INFO - PROCESS | 22727 | 04:33:36 INFO - PROCESS | 22727 | 1466681616793 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] 04:35:48 INFO - PROCESS | 22727 | Cycle 1(1): loaded http://localhost:38762/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38762/tests/dromaeo/cssquery-ext.html) 04:35:50 INFO - PROCESS | 22727 | RSS: Main: 189911040 04:35:50 INFO - PROCESS | 22727 | 04:38:52 INFO - PROCESS | 22727 | Cycle 1(1): loaded http://localhost:38762/tests/dromaeo/cssquery-ext.html (next: http://localhost:38762/tests/dromaeo/cssquery-jquery.html) 04:38:53 INFO - PROCESS | 22727 | RSS: Main: 188166144 04:38:53 INFO - PROCESS | 22727 | 04:41:54 INFO - PROCESS | 22727 | Cycle 1(1): loaded http://localhost:38762/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38762/tests/dromaeo/cssquery-mootools.html) 04:41:55 INFO - PROCESS | 22727 | RSS: Main: 189263872 04:41:55 INFO - PROCESS | 22727 | 04:44:57 INFO - PROCESS | 22727 | Cycle 1(1): loaded http://localhost:38762/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38762/tests/dromaeo/cssquery-prototype.html) 04:44:58 INFO - PROCESS | 22727 | RSS: Main: 187015168 04:44:58 INFO - PROCESS | 22727 | 04:48:00 INFO - PROCESS | 22727 | Cycle 1(1): loaded http://localhost:38762/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38762/tests/dromaeo/cssquery-yui.html) 04:48:01 INFO - PROCESS | 22727 | RSS: Main: 189124608 04:48:01 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | Cycle 1(1): loaded http://localhost:38762/tests/dromaeo/cssquery-yui.html (next: http://localhost:38762/tests/dromaeo/cssquery-dojo.html) 04:51:04 INFO - PROCESS | 22727 | RSS: Main: 189927424 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | __start_tp_report 04:51:04 INFO - PROCESS | 22727 | _x_x_mozilla_page_load 04:51:04 INFO - PROCESS | 22727 | _x_x_mozilla_page_load_details 04:51:04 INFO - PROCESS | 22727 | |i|pagename|runs| 04:51:04 INFO - PROCESS | 22727 | |0;dojo.html;2633.0126202673996;2660.108863530717;2660.255128564003;2700.2439316991236;2703.1485082199106;4899.461059283476;4903.681260718051;4919.163742163832;4986.551210391064;4993.800247990076;4832.154372984723;4888.046830867982;4892.584130348924;4893.4861839506875;4951.0592987332475;4441.622462090726;4477.395551600524;4579.267317229227;4583.12920545097;4584.510034238869;440.0199512151794;4434.312681534351;4481.305397663351;4495.707778994355;4526.343680166374;4537.7277363358135;4575.931361029588;4577.610903073243;4582.266837306015;4618.376519169903;4566.337881007268;4581.1753884300815;4593.885152871171;4598.080383923195;4639.512851150648;4377.037051848588;4460.286354183315;4480.753558554278;4555.134524440346;4559.521250268707;4933.087378834891;4947.134251505972;4960.851174464772;4988.87527811804;5055.115354812893;5319.468053194688;5376.086065368897;5381.461853814626;5398.73006349681;5399.406065332811;5400.378956420022;5425.050616142159;5453.045716999509;5457.945420545783;5501.422350653163;5059.569936555397;5063.696178229318;5064.797408103708;5067.037262920034;5125.077486052546;5348.465153484621;5469.863253418617;5471.616986810965;5503.614746967673;5539.086050801653;5215.947840521622;5223.817166399158;5229.529342359206;5230.738463076831;5233.2935053768015;5321.946780532223;5335.33308336458;5336;5341.0653135701095;5377.542908852792;5281.339832520935;5318.042752304545;5346.278252435949;5372.086745253317;5389;5274.3670759509105;5297.655652382583;5344.759485823147;5356.303680521507;5370.838874833761;4462.642988560908;4564.109998550317;4580.450345958507;4595.563421475022;4598.080383923162;3824.521934758155;3885.1258466845297;3908.257431088084;3928.6464218219926;3966.861159859449;4496.280595104833;4508.977455112704;4532.410786597721;4555.40779698637;4619.653525985565;5250.3962044364625;5326.733663316896;5339.385970613429;5349.117395629755;5357.196420536873;4536.251518499411;4574.290984897346;4580.335851301543;4613.238815595456;4650.4186976627925;4836.709797412096;4839.782209800567;4841;4845.370101886732;4845.6608037437745;4878.121938051185;4918.631102667243;4939;4991.47589503097;4999.075171093286;5307.65500242483;5315.468453154808;5317.9468205319;5324.707141107277;5350.903064871614;4948.331975183304;4974.129527332775;4974.278729584263;4976.278439626306;4998.225275082368;5113.616478764033;5211.4527974562125;5228.895422091613;5266.8946621068135;5343.9198412024625;5390.51485366319;5396.811111611075;5400.378956420062;5415.241866138823;5425.348958125051;5220.190870415092;5245.108331583564;5249.396319423163;5257.395399529103;5291.3121294231505;5064.873378165576;5066.290719299227;5091.338126043591;5110.284560161506;5131.281620573048;5032.3961124665275;5052.772625231947;5056.873578160428;5075.390953085654;5106.617003724809;5061.772220250171;5185.507376799198;5197.818076367309;5204.921926171035;5207.661502002357;4943.530364615356;5005.824796132118;5088.669236499616;5094.8216812412875;5117.309163262912 04:51:04 INFO - PROCESS | 22727 | |1;ext.html;535.6331396416864;585.765693722511;592.5318997991587;601.4977493792682;609.9173966209979;35711.464328035065;36216.09459763507;38988.41517377242;39120.41319380212;39305;40462.39306410399;41159.79420102903;41167.17665646685;41385;41478.37782433266;7866.763997080083;8027.076886158108;8030.437869349148;8041.19588041197;8057.6374063167295;7783.883241751379;7902.446828721992;7934.523928564276;7986.880196797053;8020.959895200516;7979.0824055233525;8013.398995075393;8037.397195210353;8044.155363686817;8048.436609437342;7961.164077771838;8008.639611217509;8013.11855695873;8016.519008859458;8018.118007019194;7916.129225785159;7925.484843485155;7965.2831245187635;8020.919790802076;8030.959845200737;38919.22161556781;39155.41266880999;39284.41073383902;39295.60704392948;39327.60672393268;3093.2421556718523;3212.1969507623094;3278.459054256045;3309.12308238317;3558.3061303045915;28488;29161;29629.40741185186;29927.353995530237;30152.24619384511;3559.982200089009;3583.408737558326;3620.348337299285;3629.800360980145;3642.8724994625063;260261;261265;261338;262145.9999999981;262213;31899.52150717741;51255;51767.741161293954;51839;51842.74078629583;31653;31945.68054319427;31969.68030319667;32074.83962580172;32171.839140804146;13232.801507977389;13497.86502134966;13514.189148650916;13533.323333833268;13535.39090740919;11111.277766945122;11171.21801473889;11191.160662950311;11214.383208923424;11229.922464344123;7869.567173805382;7873.212678732081;7902.920970790334;7983.441159118922;7989.520628762293;3066.7699922505453;3119.906402807919;3142.214446388403;3164.5727826743555;3177.1421716136515;853.4452605806151;884.4693184089494;908.727781106443;913;972.3193764364972;28262.71737282601;28507.429851402856;28831.42337153287;29121;29141;889.6485888074304;935.2004036548789;950.3632566180676;952.8094381123803;1024.4365598920565;14877.702445951238;14957.551273461813;15279.694406111816;15303.234838258042;15509.612259693598;385729;386143;386582;386723;389728;49472;49714.751426242634;49956;49960;50150;1165.935873526964;1169.5146514196513;1191.755690083531;1199.9880001199876;1211.5759484180642;26688.332791680365;27778.305542360795;27840.44319113566;28100.578491322238;28310;42201.577984219766;42577.787111064245;42832;42872.78563607162;42951.355729664705;331684;333379;335151;335515;335694;24858.627120592842;25371.492570148126;25381.74618253794;25443.36391590225;26245.868770656023;42133.36799947942;42293;42605;42917;42966.57033429626;36080.639193608775;36293.81853090718;36340.27319453649;36418;36749.44875826918;36277.27445451129;36405.45391819072;36647;36908;37507;31620.683793161774;32889.506657400634;33064.33871322608;35340.469892951114;35576.46635300421;29264.70735292705;30980.380392392475;31780;31877.681223187472;31936;24603.261902142964;24817.751822481543;24909.377265568506;25018.499630006932;25023.624645630694 04:51:04 INFO - PROCESS | 22727 | |2;jquery.html;370.3001327491041;373.31310388884447;377.67897287305794;382.75312423486866;392.4917232184321;15908.761368579466;16287.511374658765;16931.746023809654;17010.234539445722;17024.914875425606;15548.84451155488;16171.191440427961;16446.424375146842;16459.91770041151;16460.67078658426;5062.898742025157;5264.184051472028;5299.49654782795;5299.867503312429;5305.310309659739;3658.378075727133;5176.663516871391;5818.825435236929;6122.724477398506;6123.050927106284;6163.506919446433;6175.691215439232;6175.950088484969;6177.598456100362;6204;5086.364204474441;5402.054640437927;5413.431589683066;5439.265699130607;5453.318335208099;5052.671576347526;5265.894682106336;5290.841274761763;5292.735363231823;5324.307839980816;12236.510539578407;12408.62774116778;12452.626421207378;12488.375581220993;12834.935825320907;1888.962220755591;1982.6034793041306;2183.6942828004094;2331.834082958521;2441.07239249086;9276.72169834906;9423.48170850603;9643.903560964442;9696.321257511978;9750.219982401391;1719.5443207550009;1719.613087055415;1730.7057800173875;1730.7144321187116;1809.2039502618807;67294.32705672979;68207;69015;69317.65341173262;71805.28194718038;9895.851562226573;9932.851007234898;10254.743631409274;10274.640387586398;10306.072453479073;13721.108127971649;13752.381142848595;13807.102538334975;13963.650908727363;13969.860301397059;7433.033705618235;7445.962770186223;7456.51532650376;7552.433567482461;7625.6568454419685;7042.823929401703;7060.258672839278;7268.273172682795;7270.276342394925;7352.852942941111;4260.808263628082;4280.165367753259;4375.321825117112;4382.2112019836095;4430.335449682509;1666.7166581681142;1710.204754789029;1829.1220214296945;1869.3270422647836;1871.700527915527;763.8930549723043;872.0799556467987;880.3969281042506;880.9867851982225;883.6597909804643;16450.835491645168;16715.16424178786;16734.832651673572;16805.411810586815;17186;827.8592639251342;885.9911400886037;892.7455675132557;901.7970956534858;911.3322619606238;9377.624895004259;10285.74285642865;10428.27002109845;10470.581176752998;10497.055265026032;418793;426441;440967;442150;443396;51379.22931156035;64857;74050;85674.57162714147;86811;887.1084560017141;889.0464164931341;890.2166093837511;901.4951627088851;905.0723008915913;14161.079529830527;14174.929125354307;15688;15864.206789660702;16306.26621801998;37733.4339984895;37803.43294850634;38174;38761.6123838758;38992.805035975776;50708.239376408645;54066.459335407715;54157.45842541524;54381.45618543922;55250.44749552614;6631.33686631333;6978.371946524837;7179.569225846466;7197.748078817216;7205.567665940061;11198;11890.643280701594;11929.701757456132;12009.519619215134;12014.459349329127;10369.377837329785;10449;10467.371957682564;10582.947085264525;10896.346219226554;10611.469426528796;10631.521581528696;10674.092702120406;10735.838962415413;10913.781724365626;10454.738631534272;10456.63401780934;10490.370577765358;10554.2084343673;10780.568777248822;7047.119110111236;7091.042709234187;7187.389071928945;7272.527285726411;7347.853042939005;9966.55150518243;10230.6419275325;10433.686989390331;10582.576696932036;10740.516676749408 04:51:04 INFO - PROCESS | 22727 | |3;mootools.html;1348.1102472368236;1378.427952399754;1453.847346028667;1642.597563596919;1665.025959813509;6749.460043196545;7398.7410440634585;7507.061617297838;8043.356531477482;8056.274935255826;7611.353034992032;7800.2589753973425;7803.258690424401;7823.726169584065;7832.686692532307;2178.3900507857784;2219.655953327237;2224.021430570544;2228.220122956968;2230.141395562704;2156.3962090614655;2160.4706846822473;2190.3866917263113;2194.199117322182;2195.5279614882797;2206.823454123666;2211.3918672365066;2224.3660556741333;2227.5322182341647;2228.765979572146;2152.7309086364203;2153.676948457728;2183.879886606236;2184.8252139828774;2185.2897808212315;2125.2667829598763;2152.418846911325;2152.44036550498;2167.880766557839;2168.8481806273567;7832.451728379016;7847.1368149503505;7868.449208555404;7880.330171935392;7915.525068495908;4705.200115980258;4803.3995750531185;4807.158747219258;4812.35033270511;4815.133276010317;2928.180109569324;2996.2060054085687;2999.07028821064;3028.787984841062;3035;923.6120829251663;956.9712908612753;964.522561332138;966.9226461883032;986.6053578568558;10721.946390268076;10809.837852432298;10819.296745711503;10891.945540272327;10907.18196135293;4166.64583510405;4277.807498662568;4349.412829268008;4398.032432864765;4399.098184872158;9045.366824322235;9045.412048216846;9067.54662266884;9115.316351273681;9172.724818255463;4680.134175177603;4689.273162659793;4693.55411235932;4729.952700472952;4731.503192164773;3692.649198326155;4093.8771836844944;4250;4274.914501709948;4312.245357062501;3644.85420583179;3677.595464498903;3695.2978934002454;3751.9249615008093;3774.679152272088;1166.6966588687003;1197.1978774221118;1198.6215851770535;1208;1209.2442223610244;762.7406681728239;796.8207153390557;816.4978538198965;825.1006403020737;830.5764060329154;4933.432655244621;5035.571976381975;5134.922976155361;5162.6386152968935;5271.288376069181;805.1746959366579;844.4679851693394;873.1617647058767;873.724776695339;886.8625363068735;3399.6260411354733;3423.281110966725;3495.213576945167;3516.4725291206523;3521.542199514047;9464.337496375325;9661.32370734056;9668.129868311886;9764.121229089416;9785.168260697777;9697.466639334763;9717.902820971842;9733.367331123454;9740.707778766648;9741.65904193364;7017.122859642544;7254.927450725425;7457.589832559262;7457.664405101784;7461.552306861606;5093.286939828501;5105.2086926526445;5125.154349532435;5242.239875218148;5291.9470805292995;8892.866607000771;8937.79339789146;8959.820803583762;9023.142801433853;9154.67958621445;8907.55462226899;9116.1339672731;9240.353175277665;9292.117248861347;9336.673216437392;6011.639301641915;6419.903701444388;6674.299198584273;6677.732890684319;6854.897176542256;7548.962255188909;7551.358134558624;7570.545767253762;7603;7645.464817462725;6723.966380168068;6993.720251189896;7034.753783617549;7085.326893945273;7155.642217889189;6907.827304317231;6907.93092069093;6986.476014298846;7019.087518622547;7046.682899269443;5998.790042348497;6022.187004754477;6060.515158787374;6095.603785753912;6128.816135515942;4424.269995450714;4507.6168525674375;4511.255642819029;4517.548245175456;4542.3413605027745;4438.689291749546;4450.732956022649;4485.48416932057;4488.0126372198065;4554.612857906983 04:51:04 INFO - PROCESS | 22727 | |4;prototype.html;302.66101965798305;305.5157575243239;308.39092791736323;314.49680511182095;318.1537111283985;5154.304168937197;5188.7405629718505;5198.220266959958;5209.400918894328;5219.138842091055;5050.141475949088;5053.949460505394;5070.706969722715;5082.415522214947;5088.287639730441;3758.323501769687;3846.9807650961848;3969.2260009298197;3975.3639417693175;3989.1223930735337;3971.1660551284267;3971.62269584389;3989.6608788253034;3995.3407687731487;4016.4778578784862;3928.7624398314583;3947.8353885603674;3958.129211573449;3962.3264045112255;4001.159756451149;3676.0149519259835;3926.0577461409343;3938.862139825121;3992.3811809169624;4001.359782434811;3389.305192435545;3414.4878268259713;3417.248205394834;3421.623621401644;3422.4524076147695;4953.43035550909;4957.355543779322;4981.601471882241;5022.59819214462;5036;4225.218334608107;4503.301988191835;4530.909381812346;4541.6593755468475;4554.521775213621;5247.86880327991;5352.946470535284;5360.65155764874;5369.704666243355;5390;3736.187948844808;3747.4940882980723;3758.9436158457647;3768.9057773555605;3771.679407250387;5320.494553017418;5593.720313984284;5606.803761868356;5623.184638227434;5645.971770141123;3843.0016391476274;3853.7495062820826;3875.6124387561017;3894.2795582816702;3896;3899.883003509899;3913.8845429053154;3930.606939306104;3936.6653834424396;3939.487866577384;4500.684952053391;4530.207213737583;4533.750643714628;4560.908781824412;4563.863084107482;5185.274061631375;5323.414424413311;5324.494173053554;5366.221897824872;5520.889582208333;1340.2561578323928;1361.4145917255478;1368.4115830193114;1369.349558959506;1389.1109689798536;851.6210286422482;860.2515811244133;861.9008813986471;863.2144748279161;878.2929741558107;4553.408056952641;4573.3368661544555;4582.427196600425;4589.495155532889;4616.3306320584;5388.946110538923;5417.160340147283;5488.7530061147345;5497.185928643538;5498.367687715884;4439.045605194899;4567;4586.289125185602;4612.354270402079;4626.676132670748;2957.852107394665;2982.1948074019892;2990.775691823122;3020.2600362911226;3026.8637911294045;5239.244852974296;5260.552853007461;5287.656302340336;5289.70906600133;5295.305502239246;5440.836774896759;5751.849630074002;5759.876824019276;5769.221155144001;5778.711064446761;5238.266642670029;5258.737063146904;5263.394709608444;5271.314729085195;5272.209168624737;5577.96807590597;5590.273264475592;5592.468715472024;5627.311806458128;5676.211993222287;5251.501107394792;5526.834194974157;5559.583031272751;5584.609077364546;5602.411746766531;5248.160294352885;5251.842444726664;5258.106121959199;5281.973590132178;5303.15149576066;3816.31306364857;3848.422736589534;3895;3912.650135703178;3931.980340098281;5605.215269862304;5662.348829884616;5663.886722265449;5668.773249069991;5685.403032681509;5398.3791863934575;5420.241166236651;5433.239346491573;5461;5474.425185355481;5147.871303217449;5423.430539793265;5428.945710542844;5430.565554755689;5483.533899618577;5345.305110335787;5348.331458567679;5357.8392648220615;5358.33020872391;5363.471410647947;5240.737963101906;5313.415524292247;5437.809676661298;5476.658218736435;5493.725313734218;5292.894142117213;5300.761465734128;5321.7339133042415;5366.409694933476;5382.73086345689 04:51:04 INFO - PROCESS | 22727 | |5;yui.html;622.4460230394952;661.3584822721958;710.2116650517925;723.2694978072147;764.6444403352442;12156.20984635998;12532.373381330943;13105.213687178775;13118.47526098956;13207.141535800178;11402.828957565622;11416.942915285432;11437.542498300058;11470.541178352856;11519.078473722104;610.2158726037044;743.9665215065309;761.7067429039832;784.5096814490914;797.2505844506175;800.1478425476848;801.1747899663357;801.3829351399448;803.5901690138043;804.0070512916543;828.5898480252283;830.2278880641002;831.3432388413146;833.1918039501674;839.8236370362231;819.9057108432547;822.9465084769471;827.6606591297539;829.701307529292;830.4104086098877;601.173386593434;601.720200106948;601.9946689029332;602.4397310501232;603.667982609563;13378.665533361744;13381.66545836352;13392;13411.530596429078;13466.865331346757;13727.794083088762;13953.581392558239;13957.58127256184;13973.510927117604;14228.857711422857;10146.797064058677;10200.846987295197;10289.382637041801;10734;10820.567177312898;4349.347597860315;4368.650507959355;4374.190774706658;4388.736675799462;4514.706544074625;59580.40419595792;60140;60146.09780853335;61202.38797612012;61419.385806141814;10634.73413164677;10788.838167427339;10912.56349746017;10943.179261555415;10958.068564172134;19048.80951190498;19072.33246836354;19160.137793799313;19234.8076519233;19259;6883.380495755406;6895.413889819321;7115;7136.571805691675;7166.355028047396;3796.2976849282973;3799.164183879542;3818.7135964802196;3858.3055050090693;4011.939820902689;2776.6390369251867;2884.6971068037556;2885.6825749167574;2889.422115576851;2891.8120322178993;487.67813243259985;509.35566508366946;510.0258506253113;543.383259999903;578.9334226563915;603.447845221626;625.9123722678829;629.4492319220682;632.2318383164477;649.5193556768052;8006.439549231614;8246.422750407413;8546.957265213758;8566.91433085661;8726.214640682369;545.3864402547134;593.175486074357;630.3255516597288;639.6322114784018;647.1328419917334;3997.2804895119157;4161.479815023122;4197.76912269822;4321.135772845443;4398.47218333802;99106;99532.50233748785;103475.48262258791;103624;103726.48136759269;53299.733501332244;78089.2191078082;81954;86235;86539;58578.70710646419;78586;79373.20626793889;79496.20503795004;80500;8951.239144672776;8957.462552246887;8990.55047247622;9020.684276050306;9024.593893274687;31931;32862.507062394565;43286.56713432825;43293.13413731645;44188.337174943044;65098;67394.66302668455;69486.30513694798;70003;70830.64584677044;11747.941260293645;11773.941130294294;11832.76334473323;11939.104567157325;12251.264924104582;13454.358759638902;13469.528566500125;13472.461101555828;13678.042537022313;13772.173669579857;11933.641990740292;12134.757304853676;12340.259584424963;12923.483060677843;12937.094403391673;12143.149979501704;12399.132060756021;12573.308468034345;12640.178388404724;13278.988834886402;12516.624501264976;12536.37318134108;12548.811767823307;13044.413001414769;13171.209727416002;10548.472576371305;10680.62617808373;10795.514201861091;10936.343819370863;11011.394373309546;9942.751431214278;11169.162312826733;11202.775944481227;11235.15736319763;11249.71875703114 04:51:04 INFO - PROCESS | 22727 | __end_tp_report 04:51:04 INFO - PROCESS | 22727 | __start_cc_report 04:51:04 INFO - PROCESS | 22727 | _x_x_mozilla_cycle_collect,389 04:51:04 INFO - PROCESS | 22727 | __end_cc_report 04:51:04 INFO - PROCESS | 22727 | __startTimestamp1466682664399__endTimestamp 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | ------- Summary: start ------- 04:51:04 INFO - PROCESS | 22727 | Number of tests: 6 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | [#0] dojo.html Cycles:165 Average:4885.76 Median:5052.77 stddev:642.80 (12.7%) stddev-sans-first:620.00 04:51:04 INFO - PROCESS | 22727 | Values: 2633.0 2660.1 2660.3 2700.2 2703.1 4899.5 4903.7 4919.2 4986.6 4993.8 4832.2 4888.0 4892.6 4893.5 4951.1 4441.6 4477.4 4579.3 4583.1 4584.5 440.0 4434.3 4481.3 4495.7 4526.3 4537.7 4575.9 4577.6 4582.3 4618.4 4566.3 4581.2 4593.9 4598.1 4639.5 4377.0 4460.3 4480.8 4555.1 4559.5 4933.1 4947.1 4960.9 4988.9 5055.1 5319.5 5376.1 5381.5 5398.7 5399.4 5400.4 5425.1 5453.0 5457.9 5501.4 5059.6 5063.7 5064.8 5067.0 5125.1 5348.5 5469.9 5471.6 5503.6 5539.1 5215.9 5223.8 5229.5 5230.7 5233.3 5321.9 5335.3 5336.0 5341.1 5377.5 5281.3 5318.0 5346.3 5372.1 5389.0 5274.4 5297.7 5344.8 5356.3 5370.8 4462.6 4564.1 4580.5 4595.6 4598.1 3824.5 3885.1 3908.3 3928.6 3966.9 4496.3 4509.0 4532.4 4555.4 4619.7 5250.4 5326.7 5339.4 5349.1 5357.2 4536.3 4574.3 4580.3 4613.2 4650.4 4836.7 4839.8 4841.0 4845.4 4845.7 4878.1 4918.6 4939.0 4991.5 4999.1 5307.7 5315.5 5317.9 5324.7 5350.9 4948.3 4974.1 4974.3 4976.3 4998.2 5113.6 5211.5 5228.9 5266.9 5343.9 5390.5 5396.8 5400.4 5415.2 5425.3 5220.2 5245.1 5249.4 5257.4 5291.3 5064.9 5066.3 5091.3 5110.3 5131.3 5032.4 5052.8 5056.9 5075.4 5106.6 5061.8 5185.5 5197.8 5204.9 5207.7 4943.5 5005.8 5088.7 5094.8 5117.3 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | [#1] ext.html Cycles:180 Average:46982.53 Median:36317.05 stddev:87487.45 (240.9%) stddev-sans-first:87663.37 04:51:04 INFO - PROCESS | 22727 | Values: 535.6 585.8 592.5 601.5 609.9 35711.5 36216.1 38988.4 39120.4 39305.0 40462.4 41159.8 41167.2 41385.0 41478.4 7866.8 8027.1 8030.4 8041.2 8057.6 7783.9 7902.4 7934.5 7986.9 8021.0 7979.1 8013.4 8037.4 8044.2 8048.4 7961.2 8008.6 8013.1 8016.5 8018.1 7916.1 7925.5 7965.3 8020.9 8031.0 38919.2 39155.4 39284.4 39295.6 39327.6 3093.2 3212.2 3278.5 3309.1 3558.3 28488.0 29161.0 29629.4 29927.4 30152.2 3560.0 3583.4 3620.3 3629.8 3642.9 260261.0 261265.0 261338.0 262146.0 262213.0 31899.5 51255.0 51767.7 51839.0 51842.7 31653.0 31945.7 31969.7 32074.8 32171.8 13232.8 13497.9 13514.2 13533.3 13535.4 11111.3 11171.2 11191.2 11214.4 11229.9 7869.6 7873.2 7902.9 7983.4 7989.5 3066.8 3119.9 3142.2 3164.6 3177.1 853.4 884.5 908.7 913.0 972.3 28262.7 28507.4 28831.4 29121.0 29141.0 889.6 935.2 950.4 952.8 1024.4 14877.7 14957.6 15279.7 15303.2 15509.6 385729.0 386143.0 386582.0 386723.0 389728.0 49472.0 49714.8 49956.0 49960.0 50150.0 1165.9 1169.5 1191.8 1200.0 1211.6 26688.3 27778.3 27840.4 28100.6 28310.0 42201.6 42577.8 42832.0 42872.8 42951.4 331684.0 333379.0 335151.0 335515.0 335694.0 24858.6 25371.5 25381.7 25443.4 26245.9 42133.4 42293.0 42605.0 42917.0 42966.6 36080.6 36293.8 36340.3 36418.0 36749.4 36277.3 36405.5 36647.0 36908.0 37507.0 31620.7 32889.5 33064.3 35340.5 35576.5 29264.7 30980.4 31780.0 31877.7 31936.0 24603.3 24817.8 24909.4 25018.5 25023.6 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | [#2] jquery.html Cycles:180 Average:25348.43 Median:4327.74 stddev:71473.23 (1651.5%) stddev-sans-first:71649.12 04:51:04 INFO - PROCESS | 22727 | Values: 370.3 373.3 377.7 382.8 392.5 15908.8 16287.5 16931.7 17010.2 17024.9 15548.8 16171.2 16446.4 16459.9 16460.7 5062.9 5264.2 5299.5 5299.9 5305.3 3658.4 5176.7 5818.8 6122.7 6123.1 6163.5 6175.7 6176.0 6177.6 6204.0 5086.4 5402.1 5413.4 5439.3 5453.3 5052.7 5265.9 5290.8 5292.7 5324.3 12236.5 12408.6 12452.6 12488.4 12834.9 1889.0 1982.6 2183.7 2331.8 2441.1 9276.7 9423.5 9643.9 9696.3 9750.2 1719.5 1719.6 1730.7 1730.7 1809.2 67294.3 68207.0 69015.0 69317.7 71805.3 9895.9 9932.9 10254.7 10274.6 10306.1 13721.1 13752.4 13807.1 13963.7 13969.9 7433.0 7446.0 7456.5 7552.4 7625.7 7042.8 7060.3 7268.3 7270.3 7352.9 4260.8 4280.2 4375.3 4382.2 4430.3 1666.7 1710.2 1829.1 1869.3 1871.7 763.9 872.1 880.4 881.0 883.7 16450.8 16715.2 16734.8 16805.4 17186.0 827.9 886.0 892.7 901.8 911.3 9377.6 10285.7 10428.3 10470.6 10497.1 418793.0 426441.0 440967.0 442150.0 443396.0 51379.2 64857.0 74050.0 85674.6 86811.0 887.1 889.0 890.2 901.5 905.1 14161.1 14174.9 15688.0 15864.2 16306.3 37733.4 37803.4 38174.0 38761.6 38992.8 50708.2 54066.5 54157.5 54381.5 55250.4 6631.3 6978.4 7179.6 7197.7 7205.6 11198.0 11890.6 11929.7 12009.5 12014.5 10369.4 10449.0 10467.4 10582.9 10896.3 10611.5 10631.5 10674.1 10735.8 10913.8 10454.7 10456.6 10490.4 10554.2 10780.6 7047.1 7091.0 7187.4 7272.5 7347.9 9966.6 10230.6 10433.7 10582.6 10740.5 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | [#3] mootools.html Cycles:180 Average:5160.82 Median:5148.78 stddev:2899.85 (56.3%) stddev-sans-first:2893.83 04:51:04 INFO - PROCESS | 22727 | Values: 1348.1 1378.4 1453.8 1642.6 1665.0 6749.5 7398.7 7507.1 8043.4 8056.3 7611.4 7800.3 7803.3 7823.7 7832.7 2178.4 2219.7 2224.0 2228.2 2230.1 2156.4 2160.5 2190.4 2194.2 2195.5 2206.8 2211.4 2224.4 2227.5 2228.8 2152.7 2153.7 2183.9 2184.8 2185.3 2125.3 2152.4 2152.4 2167.9 2168.8 7832.5 7847.1 7868.4 7880.3 7915.5 4705.2 4803.4 4807.2 4812.4 4815.1 2928.2 2996.2 2999.1 3028.8 3035.0 923.6 957.0 964.5 966.9 986.6 10721.9 10809.8 10819.3 10891.9 10907.2 4166.6 4277.8 4349.4 4398.0 4399.1 9045.4 9045.4 9067.5 9115.3 9172.7 4680.1 4689.3 4693.6 4730.0 4731.5 3692.6 4093.9 4250.0 4274.9 4312.2 3644.9 3677.6 3695.3 3751.9 3774.7 1166.7 1197.2 1198.6 1208.0 1209.2 762.7 796.8 816.5 825.1 830.6 4933.4 5035.6 5134.9 5162.6 5271.3 805.2 844.5 873.2 873.7 886.9 3399.6 3423.3 3495.2 3516.5 3521.5 9464.3 9661.3 9668.1 9764.1 9785.2 9697.5 9717.9 9733.4 9740.7 9741.7 7017.1 7254.9 7457.6 7457.7 7461.6 5093.3 5105.2 5125.2 5242.2 5291.9 8892.9 8937.8 8959.8 9023.1 9154.7 8907.6 9116.1 9240.4 9292.1 9336.7 6011.6 6419.9 6674.3 6677.7 6854.9 7549.0 7551.4 7570.5 7603.0 7645.5 6724.0 6993.7 7034.8 7085.3 7155.6 6907.8 6907.9 6986.5 7019.1 7046.7 5998.8 6022.2 6060.5 6095.6 6128.8 4424.3 4507.6 4511.3 4517.5 4542.3 4438.7 4450.7 4485.5 4488.0 4554.6 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | [#4] prototype.html Cycles:180 Average:4456.66 Median:5151.09 stddev:1318.58 (25.6%) stddev-sans-first:1284.89 04:51:04 INFO - PROCESS | 22727 | Values: 302.7 305.5 308.4 314.5 318.2 5154.3 5188.7 5198.2 5209.4 5219.1 5050.1 5053.9 5070.7 5082.4 5088.3 3758.3 3847.0 3969.2 3975.4 3989.1 3971.2 3971.6 3989.7 3995.3 4016.5 3928.8 3947.8 3958.1 3962.3 4001.2 3676.0 3926.1 3938.9 3992.4 4001.4 3389.3 3414.5 3417.2 3421.6 3422.5 4953.4 4957.4 4981.6 5022.6 5036.0 4225.2 4503.3 4530.9 4541.7 4554.5 5247.9 5352.9 5360.7 5369.7 5390.0 3736.2 3747.5 3758.9 3768.9 3771.7 5320.5 5593.7 5606.8 5623.2 5646.0 3843.0 3853.7 3875.6 3894.3 3896.0 3899.9 3913.9 3930.6 3936.7 3939.5 4500.7 4530.2 4533.8 4560.9 4563.9 5185.3 5323.4 5324.5 5366.2 5520.9 1340.3 1361.4 1368.4 1369.3 1389.1 851.6 860.3 861.9 863.2 878.3 4553.4 4573.3 4582.4 4589.5 4616.3 5388.9 5417.2 5488.8 5497.2 5498.4 4439.0 4567.0 4586.3 4612.4 4626.7 2957.9 2982.2 2990.8 3020.3 3026.9 5239.2 5260.6 5287.7 5289.7 5295.3 5440.8 5751.8 5759.9 5769.2 5778.7 5238.3 5258.7 5263.4 5271.3 5272.2 5578.0 5590.3 5592.5 5627.3 5676.2 5251.5 5526.8 5559.6 5584.6 5602.4 5248.2 5251.8 5258.1 5282.0 5303.2 3816.3 3848.4 3895.0 3912.7 3932.0 5605.2 5662.3 5663.9 5668.8 5685.4 5398.4 5420.2 5433.2 5461.0 5474.4 5147.9 5423.4 5428.9 5430.6 5483.5 5345.3 5348.3 5357.8 5358.3 5363.5 5240.7 5313.4 5437.8 5476.7 5493.7 5292.9 5300.8 5321.7 5366.4 5382.7 04:51:04 INFO - PROCESS | 22727 | 04:51:04 INFO - PROCESS | 22727 | [#5] yui.html Cycles:180 Average:17938.58 Median:4359.00 stddev:25432.43 (583.4%) stddev-sans-first:25470.54 04:51:04 INFO - PROCESS | 22727 | Values: 622.4 661.4 710.2 723.3 764.6 12156.2 12532.4 13105.2 13118.5 13207.1 11402.8 11416.9 11437.5 11470.5 11519.1 610.2 744.0 761.7 784.5 797.3 800.1 801.2 801.4 803.6 804.0 828.6 830.2 831.3 833.2 839.8 819.9 822.9 827.7 829.7 830.4 601.2 601.7 602.0 602.4 603.7 13378.7 13381.7 13392.0 13411.5 13466.9 13727.8 13953.6 13957.6 13973.5 14228.9 10146.8 10200.8 10289.4 10734.0 10820.6 4349.3 4368.7 4374.2 4388.7 4514.7 59580.4 60140.0 60146.1 61202.4 61419.4 10634.7 10788.8 10912.6 10943.2 10958.1 19048.8 19072.3 19160.1 19234.8 19259.0 6883.4 6895.4 7115.0 7136.6 7166.4 3796.3 3799.2 3818.7 3858.3 4011.9 2776.6 2884.7 2885.7 2889.4 2891.8 487.7 509.4 510.0 543.4 578.9 603.4 625.9 629.4 632.2 649.5 8006.4 8246.4 8547.0 8566.9 8726.2 545.4 593.2 630.3 639.6 647.1 3997.3 4161.5 4197.8 4321.1 4398.5 99106.0 99532.5 103475.5 103624.0 103726.5 53299.7 78089.2 81954.0 86235.0 86539.0 58578.7 78586.0 79373.2 79496.2 80500.0 8951.2 8957.5 8990.6 9020.7 9024.6 31931.0 32862.5 43286.6 43293.1 44188.3 65098.0 67394.7 69486.3 70003.0 70830.6 11747.9 11773.9 11832.8 11939.1 12251.3 13454.4 13469.5 13472.5 13678.0 13772.2 11933.6 12134.8 12340.3 12923.5 12937.1 12143.1 12399.1 12573.3 12640.2 13279.0 12516.6 12536.4 12548.8 13044.4 13171.2 10548.5 10680.6 10795.5 10936.3 11011.4 9942.8 11169.2 11202.8 11235.2 11249.7 04:51:04 INFO - PROCESS | 22727 | -------- Summary: end -------- 04:51:04 INFO - PROCESS | 22727 | 04:51:05 INFO - TEST-INFO | 22727: exit 0 04:51:05 INFO - TEST-OK | dromaeo_css | took 1117188ms 04:51:05 INFO - TEST-START | kraken 04:51:05 INFO - operating with platform_type : linux_ 04:51:05 INFO - Initialising browser for kraken test... 04:51:05 INFO - TEST-INFO | started process 22954 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr3HpYH/profile http://localhost:38762/getInfo.html) 04:51:10 INFO - PROCESS | 22954 | __metrics Screen width/height:1600/1200 04:51:10 INFO - PROCESS | 22954 | colorDepth:24 04:51:10 INFO - PROCESS | 22954 | Browser inner width/height: 1024/697 04:51:10 INFO - PROCESS | 22954 | __metrics 04:51:11 ERROR - PROCESS | 22954 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 04:51:12 INFO - TEST-INFO | 22954: exit 0 04:51:12 INFO - Browser initialized. 04:51:12 INFO - Running cycle 1/1 for kraken test... 04:51:12 INFO - TEST-INFO | started process 23072 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr3HpYH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:51:24 INFO - PROCESS | 23072 | RSS: Main: 185978880 04:51:24 INFO - PROCESS | 23072 | 04:51:27 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38762/tests/kraken/driver.html?testName=audio-beat-detection) 04:51:28 INFO - PROCESS | 23072 | RSS: Main: 191201280 04:51:28 INFO - PROCESS | 23072 | 04:51:30 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38762/tests/kraken/driver.html?testName=audio-dft) 04:51:31 INFO - PROCESS | 23072 | RSS: Main: 191049728 04:51:31 INFO - PROCESS | 23072 | 04:51:34 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38762/tests/kraken/driver.html?testName=audio-fft) 04:51:35 INFO - PROCESS | 23072 | RSS: Main: 186929152 04:51:35 INFO - PROCESS | 23072 | 04:51:37 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38762/tests/kraken/driver.html?testName=audio-oscillator) 04:51:37 INFO - PROCESS | 23072 | RSS: Main: 187121664 04:51:37 INFO - PROCESS | 23072 | 04:51:40 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38762/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:51:40 INFO - PROCESS | 23072 | RSS: Main: 187944960 04:51:40 INFO - PROCESS | 23072 | 04:51:43 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38762/tests/kraken/driver.html?testName=imaging-darkroom) 04:51:44 INFO - PROCESS | 23072 | RSS: Main: 187617280 04:51:44 INFO - PROCESS | 23072 | 04:51:48 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38762/tests/kraken/driver.html?testName=imaging-desaturate) 04:51:48 INFO - PROCESS | 23072 | RSS: Main: 184594432 04:51:48 INFO - PROCESS | 23072 | 04:51:51 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38762/tests/kraken/driver.html?testName=json-parse-financial) 04:51:52 INFO - PROCESS | 23072 | RSS: Main: 185942016 04:51:52 INFO - PROCESS | 23072 | 04:51:54 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38762/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:51:54 INFO - PROCESS | 23072 | RSS: Main: 186880000 04:51:54 INFO - PROCESS | 23072 | 04:51:56 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:51:57 INFO - PROCESS | 23072 | RSS: Main: 186757120 04:51:57 INFO - PROCESS | 23072 | 04:51:59 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:52:00 INFO - PROCESS | 23072 | RSS: Main: 186548224 04:52:00 INFO - PROCESS | 23072 | 04:52:02 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:52:03 INFO - PROCESS | 23072 | RSS: Main: 187314176 04:52:03 INFO - PROCESS | 23072 | 04:52:06 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:52:07 INFO - PROCESS | 23072 | RSS: Main: 187224064 04:52:07 INFO - PROCESS | 23072 | 04:52:08 INFO - PROCESS | 23072 | Cycle 1(1): loaded http://localhost:38762/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38762/tests/kraken/driver.html?testName=ai-astar) 04:52:09 INFO - PROCESS | 23072 | RSS: Main: 188317696 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | __start_tp_report 04:52:09 INFO - PROCESS | 23072 | _x_x_mozilla_page_load 04:52:09 INFO - PROCESS | 23072 | _x_x_mozilla_page_load_details 04:52:09 INFO - PROCESS | 23072 | |i|pagename|runs| 04:52:09 INFO - PROCESS | 23072 | |0;ai-astar;105;115;108;114;115;114;115;113;113;114 04:52:09 INFO - PROCESS | 23072 | |1;audio-beat-detection;163;140;162;188;133;163;158;135;193;132 04:52:09 INFO - PROCESS | 23072 | |2;audio-dft;162;164;178;165;163;162;164;163;164;162 04:52:09 INFO - PROCESS | 23072 | |3;audio-fft;78;77;77;164;77;79;76;75;173;81 04:52:09 INFO - PROCESS | 23072 | |4;audio-oscillator;87;86;145;86;86;88;138;87;86;149 04:52:09 INFO - PROCESS | 23072 | |5;imaging-gaussian-blur;119;119;118;117;117;119;117;118;121;122 04:52:09 INFO - PROCESS | 23072 | |6;imaging-darkroom;147;148;148;148;147;148;146;147;147;148 04:52:09 INFO - PROCESS | 23072 | |7;imaging-desaturate;95;95;95;95;95;97;95;95;96;97 04:52:09 INFO - PROCESS | 23072 | |8;json-parse-financial;63;65;66;65;65;64;64;64;63;64 04:52:09 INFO - PROCESS | 23072 | |9;json-stringify-tinderbox;65;66;65;64;64;65;65;66;65;65 04:52:09 INFO - PROCESS | 23072 | |10;stanford-crypto-aes;83;82;83;92;84;90;87;82;98;89 04:52:09 INFO - PROCESS | 23072 | |11;stanford-crypto-ccm;130;137;131;131;129;135;145;151;136;150 04:52:09 INFO - PROCESS | 23072 | |12;stanford-crypto-pbkdf2;174;167;171;168;171;170;168;174;169;166 04:52:09 INFO - PROCESS | 23072 | |13;stanford-crypto-sha256-iterative;69;66;68;68;68;68;70;66;66;67 04:52:09 INFO - PROCESS | 23072 | __end_tp_report 04:52:09 INFO - PROCESS | 23072 | __start_cc_report 04:52:09 INFO - PROCESS | 23072 | _x_x_mozilla_cycle_collect,978 04:52:09 INFO - PROCESS | 23072 | __end_cc_report 04:52:09 INFO - PROCESS | 23072 | __startTimestamp1466682729105__endTimestamp 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | ------- Summary: start ------- 04:52:09 INFO - PROCESS | 23072 | Number of tests: 14 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.37 (3.0%) stddev-sans-first:2.19 04:52:09 INFO - PROCESS | 23072 | Values: 105.0 115.0 108.0 114.0 115.0 114.0 115.0 113.0 113.0 114.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#1] audio-beat-detection Cycles:10 Average:156.70 Median:162.50 stddev:21.93 (13.5%) stddev-sans-first:23.14 04:52:09 INFO - PROCESS | 23072 | Values: 163.0 140.0 162.0 188.0 133.0 163.0 158.0 135.0 193.0 132.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#2] audio-dft Cycles:10 Average:164.70 Median:164.00 stddev:4.79 (2.9%) stddev-sans-first:4.97 04:52:09 INFO - PROCESS | 23072 | Values: 162.0 164.0 178.0 165.0 163.0 162.0 164.0 163.0 164.0 162.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#3] audio-fft Cycles:10 Average:95.70 Median:77.00 stddev:38.46 (50.0%) stddev-sans-first:40.26 04:52:09 INFO - PROCESS | 23072 | Values: 78.0 77.0 77.0 164.0 77.0 79.0 76.0 75.0 173.0 81.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:86.00 stddev:27.87 (32.4%) stddev-sans-first:28.89 04:52:09 INFO - PROCESS | 23072 | Values: 87.0 86.0 145.0 86.0 86.0 88.0 138.0 87.0 86.0 149.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#5] imaging-gaussian-blur Cycles:10 Average:118.70 Median:119.00 stddev:1.70 (1.4%) stddev-sans-first:1.80 04:52:09 INFO - PROCESS | 23072 | Values: 119.0 119.0 118.0 117.0 117.0 119.0 117.0 118.0 121.0 122.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 04:52:09 INFO - PROCESS | 23072 | Values: 147.0 148.0 148.0 148.0 147.0 148.0 146.0 147.0 147.0 148.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 04:52:09 INFO - PROCESS | 23072 | Values: 95.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 96.0 97.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.50 stddev:0.95 (1.5%) stddev-sans-first:0.88 04:52:09 INFO - PROCESS | 23072 | Values: 63.0 65.0 66.0 65.0 65.0 64.0 64.0 64.0 63.0 64.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 04:52:09 INFO - PROCESS | 23072 | Values: 65.0 66.0 65.0 64.0 64.0 65.0 65.0 66.0 65.0 65.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:88.00 stddev:5.27 (6.0%) stddev-sans-first:5.39 04:52:09 INFO - PROCESS | 23072 | Values: 83.0 82.0 83.0 92.0 84.0 90.0 87.0 82.0 98.0 89.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:136.50 stddev:8.28 (6.1%) stddev-sans-first:8.32 04:52:09 INFO - PROCESS | 23072 | Values: 130.0 137.0 131.0 131.0 129.0 135.0 145.0 151.0 136.0 150.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.50 stddev:2.74 (1.6%) stddev-sans-first:2.45 04:52:09 INFO - PROCESS | 23072 | Values: 174.0 167.0 171.0 168.0 171.0 170.0 168.0 174.0 169.0 166.0 04:52:09 INFO - PROCESS | 23072 | 04:52:09 INFO - PROCESS | 23072 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.35 (2.0%) stddev-sans-first:1.33 04:52:09 INFO - PROCESS | 23072 | Values: 69.0 66.0 68.0 68.0 68.0 68.0 70.0 66.0 66.0 67.0 04:52:09 INFO - PROCESS | 23072 | -------- Summary: end -------- 04:52:09 INFO - PROCESS | 23072 | 04:52:10 INFO - TEST-INFO | 23072: exit 0 04:52:10 INFO - TEST-OK | kraken | took 64992ms 04:52:10 INFO - SUITE-END | took 1182s 04:52:10 INFO - Completed test suite (00:19:42) 04:52:10 INFO - javascript benchmark 04:52:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6490.807385603354, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2633.0126202673996, 2660.108863530717, 2660.255128564003, 2700.2439316991236, 2703.1485082199106, 4899.461059283476, 4903.681260718051, 4919.163742163832, 4986.551210391064, 4993.800247990076, 4832.154372984723, 4888.046830867982, 4892.584130348924, 4893.4861839506875, 4951.0592987332475, 4441.622462090726, 4477.395551600524, 4579.267317229227, 4583.12920545097, 4584.510034238869, 440.0199512151794, 4434.312681534351, 4481.305397663351, 4495.707778994355, 4526.343680166374, 4537.7277363358135, 4575.931361029588, 4577.610903073243, 4582.266837306015, 4618.376519169903, 4566.337881007268, 4581.1753884300815, 4593.885152871171, 4598.080383923195, 4639.512851150648, 4377.037051848588, 4460.286354183315, 4480.753558554278, 4555.134524440346, 4559.521250268707, 4933.087378834891, 4947.134251505972, 4960.851174464772, 4988.87527811804, 5055.115354812893, 5319.468053194688, 5376.086065368897, 5381.461853814626, 5398.73006349681, 5399.406065332811, 5400.378956420022, 5425.050616142159, 5453.045716999509, 5457.945420545783, 5501.422350653163, 5059.569936555397, 5063.696178229318, 5064.797408103708, 5067.037262920034, 5125.077486052546, 5348.465153484621, 5469.863253418617, 5471.616986810965, 5503.614746967673, 5539.086050801653, 5215.947840521622, 5223.817166399158, 5229.529342359206, 5230.738463076831, 5233.2935053768015, 5321.946780532223, 5335.33308336458, 5336.0, 5341.0653135701095, 5377.542908852792, 5281.339832520935, 5318.042752304545, 5346.278252435949, 5372.086745253317, 5389.0, 5274.3670759509105, 5297.655652382583, 5344.759485823147, 5356.303680521507, 5370.838874833761, 4462.642988560908, 4564.109998550317, 4580.450345958507, 4595.563421475022, 4598.080383923162, 3824.521934758155, 3885.1258466845297, 3908.257431088084, 3928.6464218219926, 3966.861159859449, 4496.280595104833, 4508.977455112704, 4532.410786597721, 4555.40779698637, 4619.653525985565, 5250.3962044364625, 5326.733663316896, 5339.385970613429, 5349.117395629755, 5357.196420536873, 4536.251518499411, 4574.290984897346, 4580.335851301543, 4613.238815595456, 4650.4186976627925, 4836.709797412096, 4839.782209800567, 4841.0, 4845.370101886732, 4845.6608037437745, 4878.121938051185, 4918.631102667243, 4939.0, 4991.47589503097, 4999.075171093286, 5307.65500242483, 5315.468453154808, 5317.9468205319, 5324.707141107277, 5350.903064871614, 4948.331975183304, 4974.129527332775, 4974.278729584263, 4976.278439626306, 4998.225275082368, 5113.616478764033, 5211.4527974562125, 5228.895422091613, 5266.8946621068135, 5343.9198412024625, 5390.51485366319, 5396.811111611075, 5400.378956420062, 5415.241866138823, 5425.348958125051, 5220.190870415092, 5245.108331583564, 5249.396319423163, 5257.395399529103, 5291.3121294231505, 5064.873378165576, 5066.290719299227, 5091.338126043591, 5110.284560161506, 5131.281620573048, 5032.3961124665275, 5052.772625231947, 5056.873578160428, 5075.390953085654, 5106.617003724809, 5061.772220250171, 5185.507376799198, 5197.818076367309, 5204.921926171035, 5207.661502002357, 4943.530364615356, 5005.824796132118, 5088.669236499616, 5094.8216812412875, 5117.309163262912], "value": 4844.105912346973, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.6331396416864, 585.765693722511, 592.5318997991587, 601.4977493792682, 609.9173966209979, 35711.464328035065, 36216.09459763507, 38988.41517377242, 39120.41319380212, 39305.0, 40462.39306410399, 41159.79420102903, 41167.17665646685, 41385.0, 41478.37782433266, 7866.763997080083, 8027.076886158108, 8030.437869349148, 8041.19588041197, 8057.6374063167295, 7783.883241751379, 7902.446828721992, 7934.523928564276, 7986.880196797053, 8020.959895200516, 7979.0824055233525, 8013.398995075393, 8037.397195210353, 8044.155363686817, 8048.436609437342, 7961.164077771838, 8008.639611217509, 8013.11855695873, 8016.519008859458, 8018.118007019194, 7916.129225785159, 7925.484843485155, 7965.2831245187635, 8020.919790802076, 8030.959845200737, 38919.22161556781, 39155.41266880999, 39284.41073383902, 39295.60704392948, 39327.60672393268, 3093.2421556718523, 3212.1969507623094, 3278.459054256045, 3309.12308238317, 3558.3061303045915, 28488.0, 29161.0, 29629.40741185186, 29927.353995530237, 30152.24619384511, 3559.982200089009, 3583.408737558326, 3620.348337299285, 3629.800360980145, 3642.8724994625063, 260261.0, 261265.0, 261338.0, 262145.9999999981, 262213.0, 31899.52150717741, 51255.0, 51767.741161293954, 51839.0, 51842.74078629583, 31653.0, 31945.68054319427, 31969.68030319667, 32074.83962580172, 32171.839140804146, 13232.801507977389, 13497.86502134966, 13514.189148650916, 13533.323333833268, 13535.39090740919, 11111.277766945122, 11171.21801473889, 11191.160662950311, 11214.383208923424, 11229.922464344123, 7869.567173805382, 7873.212678732081, 7902.920970790334, 7983.441159118922, 7989.520628762293, 3066.7699922505453, 3119.906402807919, 3142.214446388403, 3164.5727826743555, 3177.1421716136515, 853.4452605806151, 884.4693184089494, 908.727781106443, 913.0, 972.3193764364972, 28262.71737282601, 28507.429851402856, 28831.42337153287, 29121.0, 29141.0, 889.6485888074304, 935.2004036548789, 950.3632566180676, 952.8094381123803, 1024.4365598920565, 14877.702445951238, 14957.551273461813, 15279.694406111816, 15303.234838258042, 15509.612259693598, 385729.0, 386143.0, 386582.0, 386723.0, 389728.0, 49472.0, 49714.751426242634, 49956.0, 49960.0, 50150.0, 1165.935873526964, 1169.5146514196513, 1191.755690083531, 1199.9880001199876, 1211.5759484180642, 26688.332791680365, 27778.305542360795, 27840.44319113566, 28100.578491322238, 28310.0, 42201.577984219766, 42577.787111064245, 42832.0, 42872.78563607162, 42951.355729664705, 331684.0, 333379.0, 335151.0, 335515.0, 335694.0, 24858.627120592842, 25371.492570148126, 25381.74618253794, 25443.36391590225, 26245.868770656023, 42133.36799947942, 42293.0, 42605.0, 42917.0, 42966.57033429626, 36080.639193608775, 36293.81853090718, 36340.27319453649, 36418.0, 36749.44875826918, 36277.27445451129, 36405.45391819072, 36647.0, 36908.0, 37507.0, 31620.683793161774, 32889.506657400634, 33064.33871322608, 35340.469892951114, 35576.46635300421, 29264.70735292705, 30980.380392392475, 31780.0, 31877.681223187472, 31936.0, 24603.261902142964, 24817.751822481543, 24909.377265568506, 25018.499630006932, 25023.624645630694], "value": 16569.925954810602, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.3001327491041, 373.31310388884447, 377.67897287305794, 382.75312423486866, 392.4917232184321, 15908.761368579466, 16287.511374658765, 16931.746023809654, 17010.234539445722, 17024.914875425606, 15548.84451155488, 16171.191440427961, 16446.424375146842, 16459.91770041151, 16460.67078658426, 5062.898742025157, 5264.184051472028, 5299.49654782795, 5299.867503312429, 5305.310309659739, 3658.378075727133, 5176.663516871391, 5818.825435236929, 6122.724477398506, 6123.050927106284, 6163.506919446433, 6175.691215439232, 6175.950088484969, 6177.598456100362, 6204.0, 5086.364204474441, 5402.054640437927, 5413.431589683066, 5439.265699130607, 5453.318335208099, 5052.671576347526, 5265.894682106336, 5290.841274761763, 5292.735363231823, 5324.307839980816, 12236.510539578407, 12408.62774116778, 12452.626421207378, 12488.375581220993, 12834.935825320907, 1888.962220755591, 1982.6034793041306, 2183.6942828004094, 2331.834082958521, 2441.07239249086, 9276.72169834906, 9423.48170850603, 9643.903560964442, 9696.321257511978, 9750.219982401391, 1719.5443207550009, 1719.613087055415, 1730.7057800173875, 1730.7144321187116, 1809.2039502618807, 67294.32705672979, 68207.0, 69015.0, 69317.65341173262, 71805.28194718038, 9895.851562226573, 9932.851007234898, 10254.743631409274, 10274.640387586398, 10306.072453479073, 13721.108127971649, 13752.381142848595, 13807.102538334975, 13963.650908727363, 13969.860301397059, 7433.033705618235, 7445.962770186223, 7456.51532650376, 7552.433567482461, 7625.6568454419685, 7042.823929401703, 7060.258672839278, 7268.273172682795, 7270.276342394925, 7352.852942941111, 4260.808263628082, 4280.165367753259, 4375.321825117112, 4382.2112019836095, 4430.335449682509, 1666.7166581681142, 1710.204754789029, 1829.1220214296945, 1869.3270422647836, 1871.700527915527, 763.8930549723043, 872.0799556467987, 880.3969281042506, 880.9867851982225, 883.6597909804643, 16450.835491645168, 16715.16424178786, 16734.832651673572, 16805.411810586815, 17186.0, 827.8592639251342, 885.9911400886037, 892.7455675132557, 901.7970956534858, 911.3322619606238, 9377.624895004259, 10285.74285642865, 10428.27002109845, 10470.581176752998, 10497.055265026032, 418793.0, 426441.0, 440967.0, 442150.0, 443396.0, 51379.22931156035, 64857.0, 74050.0, 85674.57162714147, 86811.0, 887.1084560017141, 889.0464164931341, 890.2166093837511, 901.4951627088851, 905.0723008915913, 14161.079529830527, 14174.929125354307, 15688.0, 15864.206789660702, 16306.26621801998, 37733.4339984895, 37803.43294850634, 38174.0, 38761.6123838758, 38992.805035975776, 50708.239376408645, 54066.459335407715, 54157.45842541524, 54381.45618543922, 55250.44749552614, 6631.33686631333, 6978.371946524837, 7179.569225846466, 7197.748078817216, 7205.567665940061, 11198.0, 11890.643280701594, 11929.701757456132, 12009.519619215134, 12014.459349329127, 10369.377837329785, 10449.0, 10467.371957682564, 10582.947085264525, 10896.346219226554, 10611.469426528796, 10631.521581528696, 10674.092702120406, 10735.838962415413, 10913.781724365626, 10454.738631534272, 10456.63401780934, 10490.370577765358, 10554.2084343673, 10780.568777248822, 7047.119110111236, 7091.042709234187, 7187.389071928945, 7272.527285726411, 7347.853042939005, 9966.55150518243, 10230.6419275325, 10433.686989390331, 10582.576696932036, 10740.516676749408], "value": 8198.716665002634, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.1102472368236, 1378.427952399754, 1453.847346028667, 1642.597563596919, 1665.025959813509, 6749.460043196545, 7398.7410440634585, 7507.061617297838, 8043.356531477482, 8056.274935255826, 7611.353034992032, 7800.2589753973425, 7803.258690424401, 7823.726169584065, 7832.686692532307, 2178.3900507857784, 2219.655953327237, 2224.021430570544, 2228.220122956968, 2230.141395562704, 2156.3962090614655, 2160.4706846822473, 2190.3866917263113, 2194.199117322182, 2195.5279614882797, 2206.823454123666, 2211.3918672365066, 2224.3660556741333, 2227.5322182341647, 2228.765979572146, 2152.7309086364203, 2153.676948457728, 2183.879886606236, 2184.8252139828774, 2185.2897808212315, 2125.2667829598763, 2152.418846911325, 2152.44036550498, 2167.880766557839, 2168.8481806273567, 7832.451728379016, 7847.1368149503505, 7868.449208555404, 7880.330171935392, 7915.525068495908, 4705.200115980258, 4803.3995750531185, 4807.158747219258, 4812.35033270511, 4815.133276010317, 2928.180109569324, 2996.2060054085687, 2999.07028821064, 3028.787984841062, 3035.0, 923.6120829251663, 956.9712908612753, 964.522561332138, 966.9226461883032, 986.6053578568558, 10721.946390268076, 10809.837852432298, 10819.296745711503, 10891.945540272327, 10907.18196135293, 4166.64583510405, 4277.807498662568, 4349.412829268008, 4398.032432864765, 4399.098184872158, 9045.366824322235, 9045.412048216846, 9067.54662266884, 9115.316351273681, 9172.724818255463, 4680.134175177603, 4689.273162659793, 4693.55411235932, 4729.952700472952, 4731.503192164773, 3692.649198326155, 4093.8771836844944, 4250.0, 4274.914501709948, 4312.245357062501, 3644.85420583179, 3677.595464498903, 3695.2978934002454, 3751.9249615008093, 3774.679152272088, 1166.6966588687003, 1197.1978774221118, 1198.6215851770535, 1208.0, 1209.2442223610244, 762.7406681728239, 796.8207153390557, 816.4978538198965, 825.1006403020737, 830.5764060329154, 4933.432655244621, 5035.571976381975, 5134.922976155361, 5162.6386152968935, 5271.288376069181, 805.1746959366579, 844.4679851693394, 873.1617647058767, 873.724776695339, 886.8625363068735, 3399.6260411354733, 3423.281110966725, 3495.213576945167, 3516.4725291206523, 3521.542199514047, 9464.337496375325, 9661.32370734056, 9668.129868311886, 9764.121229089416, 9785.168260697777, 9697.466639334763, 9717.902820971842, 9733.367331123454, 9740.707778766648, 9741.65904193364, 7017.122859642544, 7254.927450725425, 7457.589832559262, 7457.664405101784, 7461.552306861606, 5093.286939828501, 5105.2086926526445, 5125.154349532435, 5242.239875218148, 5291.9470805292995, 8892.866607000771, 8937.79339789146, 8959.820803583762, 9023.142801433853, 9154.67958621445, 8907.55462226899, 9116.1339672731, 9240.353175277665, 9292.117248861347, 9336.673216437392, 6011.639301641915, 6419.903701444388, 6674.299198584273, 6677.732890684319, 6854.897176542256, 7548.962255188909, 7551.358134558624, 7570.545767253762, 7603.0, 7645.464817462725, 6723.966380168068, 6993.720251189896, 7034.753783617549, 7085.326893945273, 7155.642217889189, 6907.827304317231, 6907.93092069093, 6986.476014298846, 7019.087518622547, 7046.682899269443, 5998.790042348497, 6022.187004754477, 6060.515158787374, 6095.603785753912, 6128.816135515942, 4424.269995450714, 4507.6168525674375, 4511.255642819029, 4517.548245175456, 4542.3413605027745, 4438.689291749546, 4450.732956022649, 4485.48416932057, 4488.0126372198065, 4554.612857906983], "value": 4155.7414180057485, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.66101965798305, 305.5157575243239, 308.39092791736323, 314.49680511182095, 318.1537111283985, 5154.304168937197, 5188.7405629718505, 5198.220266959958, 5209.400918894328, 5219.138842091055, 5050.141475949088, 5053.949460505394, 5070.706969722715, 5082.415522214947, 5088.287639730441, 3758.323501769687, 3846.9807650961848, 3969.2260009298197, 3975.3639417693175, 3989.1223930735337, 3971.1660551284267, 3971.62269584389, 3989.6608788253034, 3995.3407687731487, 4016.4778578784862, 3928.7624398314583, 3947.8353885603674, 3958.129211573449, 3962.3264045112255, 4001.159756451149, 3676.0149519259835, 3926.0577461409343, 3938.862139825121, 3992.3811809169624, 4001.359782434811, 3389.305192435545, 3414.4878268259713, 3417.248205394834, 3421.623621401644, 3422.4524076147695, 4953.43035550909, 4957.355543779322, 4981.601471882241, 5022.59819214462, 5036.0, 4225.218334608107, 4503.301988191835, 4530.909381812346, 4541.6593755468475, 4554.521775213621, 5247.86880327991, 5352.946470535284, 5360.65155764874, 5369.704666243355, 5390.0, 3736.187948844808, 3747.4940882980723, 3758.9436158457647, 3768.9057773555605, 3771.679407250387, 5320.494553017418, 5593.720313984284, 5606.803761868356, 5623.184638227434, 5645.971770141123, 3843.0016391476274, 3853.7495062820826, 3875.6124387561017, 3894.2795582816702, 3896.0, 3899.883003509899, 3913.8845429053154, 3930.606939306104, 3936.6653834424396, 3939.487866577384, 4500.684952053391, 4530.207213737583, 4533.750643714628, 4560.908781824412, 4563.863084107482, 5185.274061631375, 5323.414424413311, 5324.494173053554, 5366.221897824872, 5520.889582208333, 1340.2561578323928, 1361.4145917255478, 1368.4115830193114, 1369.349558959506, 1389.1109689798536, 851.6210286422482, 860.2515811244133, 861.9008813986471, 863.2144748279161, 878.2929741558107, 4553.408056952641, 4573.3368661544555, 4582.427196600425, 4589.495155532889, 4616.3306320584, 5388.946110538923, 5417.160340147283, 5488.7530061147345, 5497.185928643538, 5498.367687715884, 4439.045605194899, 4567.0, 4586.289125185602, 4612.354270402079, 4626.676132670748, 2957.852107394665, 2982.1948074019892, 2990.775691823122, 3020.2600362911226, 3026.8637911294045, 5239.244852974296, 5260.552853007461, 5287.656302340336, 5289.70906600133, 5295.305502239246, 5440.836774896759, 5751.849630074002, 5759.876824019276, 5769.221155144001, 5778.711064446761, 5238.266642670029, 5258.737063146904, 5263.394709608444, 5271.314729085195, 5272.209168624737, 5577.96807590597, 5590.273264475592, 5592.468715472024, 5627.311806458128, 5676.211993222287, 5251.501107394792, 5526.834194974157, 5559.583031272751, 5584.609077364546, 5602.411746766531, 5248.160294352885, 5251.842444726664, 5258.106121959199, 5281.973590132178, 5303.15149576066, 3816.31306364857, 3848.422736589534, 3895.0, 3912.650135703178, 3931.980340098281, 5605.215269862304, 5662.348829884616, 5663.886722265449, 5668.773249069991, 5685.403032681509, 5398.3791863934575, 5420.241166236651, 5433.239346491573, 5461.0, 5474.425185355481, 5147.871303217449, 5423.430539793265, 5428.945710542844, 5430.565554755689, 5483.533899618577, 5345.305110335787, 5348.331458567679, 5357.8392648220615, 5358.33020872391, 5363.471410647947, 5240.737963101906, 5313.415524292247, 5437.809676661298, 5476.658218736435, 5493.725313734218, 5292.894142117213, 5300.761465734128, 5321.7339133042415, 5366.409694933476, 5382.73086345689], "value": 4033.0508845936765, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.4460230394952, 661.3584822721958, 710.2116650517925, 723.2694978072147, 764.6444403352442, 12156.20984635998, 12532.373381330943, 13105.213687178775, 13118.47526098956, 13207.141535800178, 11402.828957565622, 11416.942915285432, 11437.542498300058, 11470.541178352856, 11519.078473722104, 610.2158726037044, 743.9665215065309, 761.7067429039832, 784.5096814490914, 797.2505844506175, 800.1478425476848, 801.1747899663357, 801.3829351399448, 803.5901690138043, 804.0070512916543, 828.5898480252283, 830.2278880641002, 831.3432388413146, 833.1918039501674, 839.8236370362231, 819.9057108432547, 822.9465084769471, 827.6606591297539, 829.701307529292, 830.4104086098877, 601.173386593434, 601.720200106948, 601.9946689029332, 602.4397310501232, 603.667982609563, 13378.665533361744, 13381.66545836352, 13392.0, 13411.530596429078, 13466.865331346757, 13727.794083088762, 13953.581392558239, 13957.58127256184, 13973.510927117604, 14228.857711422857, 10146.797064058677, 10200.846987295197, 10289.382637041801, 10734.0, 10820.567177312898, 4349.347597860315, 4368.650507959355, 4374.190774706658, 4388.736675799462, 4514.706544074625, 59580.40419595792, 60140.0, 60146.09780853335, 61202.38797612012, 61419.385806141814, 10634.73413164677, 10788.838167427339, 10912.56349746017, 10943.179261555415, 10958.068564172134, 19048.80951190498, 19072.33246836354, 19160.137793799313, 19234.8076519233, 19259.0, 6883.380495755406, 6895.413889819321, 7115.0, 7136.571805691675, 7166.355028047396, 3796.2976849282973, 3799.164183879542, 3818.7135964802196, 3858.3055050090693, 4011.939820902689, 2776.6390369251867, 2884.6971068037556, 2885.6825749167574, 2889.422115576851, 2891.8120322178993, 487.67813243259985, 509.35566508366946, 510.0258506253113, 543.383259999903, 578.9334226563915, 603.447845221626, 625.9123722678829, 629.4492319220682, 632.2318383164477, 649.5193556768052, 8006.439549231614, 8246.422750407413, 8546.957265213758, 8566.91433085661, 8726.214640682369, 545.3864402547134, 593.175486074357, 630.3255516597288, 639.6322114784018, 647.1328419917334, 3997.2804895119157, 4161.479815023122, 4197.76912269822, 4321.135772845443, 4398.47218333802, 99106.0, 99532.50233748785, 103475.48262258791, 103624.0, 103726.48136759269, 53299.733501332244, 78089.2191078082, 81954.0, 86235.0, 86539.0, 58578.70710646419, 78586.0, 79373.20626793889, 79496.20503795004, 80500.0, 8951.239144672776, 8957.462552246887, 8990.55047247622, 9020.684276050306, 9024.593893274687, 31931.0, 32862.507062394565, 43286.56713432825, 43293.13413731645, 44188.337174943044, 65098.0, 67394.66302668455, 69486.30513694798, 70003.0, 70830.64584677044, 11747.941260293645, 11773.941130294294, 11832.76334473323, 11939.104567157325, 12251.264924104582, 13454.358759638902, 13469.528566500125, 13472.461101555828, 13678.042537022313, 13772.173669579857, 11933.641990740292, 12134.757304853676, 12340.259584424963, 12923.483060677843, 12937.094403391673, 12143.149979501704, 12399.132060756021, 12573.308468034345, 12640.178388404724, 13278.988834886402, 12516.624501264976, 12536.37318134108, 12548.811767823307, 13044.413001414769, 13171.209727416002, 10548.472576371305, 10680.62617808373, 10795.514201861091, 10936.343819370863, 11011.394373309546, 9942.751431214278, 11169.162312826733, 11202.775944481227, 11235.15736319763, 11249.71875703114], "value": 6779.324440607275, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 108.0, 114.0, 115.0, 114.0, 115.0, 113.0, 113.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 140.0, 162.0, 188.0, 133.0, 163.0, 158.0, 135.0, 193.0, 132.0], "value": 156.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 178.0, 165.0, 163.0, 162.0, 164.0, 163.0, 164.0, 162.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 164.0, 77.0, 79.0, 76.0, 75.0, 173.0, 81.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 145.0, 86.0, 86.0, 88.0, 138.0, 87.0, 86.0, 149.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 118.0, 117.0, 117.0, 119.0, 117.0, 118.0, 121.0, 122.0], "value": 118.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 148.0, 147.0, 148.0, 146.0, 147.0, 147.0, 148.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0, 96.0, 97.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 66.0, 65.0, 65.0, 64.0, 64.0, 64.0, 63.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 64.0, 64.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 92.0, 84.0, 90.0, 87.0, 82.0, 98.0, 89.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 137.0, 131.0, 131.0, 129.0, 135.0, 145.0, 151.0, 136.0, 150.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 167.0, 171.0, 168.0, 171.0, 170.0, 168.0, 174.0, 169.0, 166.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 68.0, 68.0, 68.0, 68.0, 70.0, 66.0, 66.0, 67.0], "value": 67.6, "unit": "score"}]}]} 04:52:10 INFO - Return code: 0 04:52:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:52:10 ERROR - # TBPL SUCCESS # 04:52:10 INFO - Running post-action listener: _resource_record_post_action 04:52:10 INFO - ##### 04:52:10 INFO - ##### Finished run-tests step (success) 04:52:10 INFO - ##### 04:52:10 INFO - Running post-run listener: _resource_record_post_run 04:52:10 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 543703040; Read time: 20; Write time: 477380 04:52:10 INFO - TinderboxPrint: CPU usage
13.4% 04:52:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 04:52:10 INFO - TinderboxPrint: I/O write bytes / time
543,703,040 / 477,380 04:52:10 INFO - TinderboxPrint: CPU idle
8,278.4 (86.6%) 04:52:10 INFO - TinderboxPrint: CPU user
1,228.4 (12.8%) 04:52:10 INFO - TinderboxPrint: Swap in / out
0 / 0 04:52:10 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 246337536; Read time: 0; Write time: 282708 04:52:10 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 297365504; Read time: 20; Write time: 194672 04:52:11 INFO - Running post-run listener: _upload_blobber_files 04:52:11 INFO - Blob upload gear active. 04:52:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:52:11 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 04:52:11 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:52:11 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:52:11 INFO - (blobuploader) - INFO - Open directory for files ... 04:52:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:52:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:52:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:14 INFO - (blobuploader) - INFO - Done attempting. 04:52:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:52:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:52:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:15 INFO - (blobuploader) - INFO - Done attempting. 04:52:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:52:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:52:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:16 INFO - (blobuploader) - INFO - Done attempting. 04:52:16 INFO - (blobuploader) - INFO - Iteration through files over. 04:52:16 INFO - Return code: 0 04:52:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:52:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:52:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0c8ba4299c6274271dedff2547945216b2d7bcbf1cad1a01ad82d6a2a2d88ec0fdba2dc66cc3bb549d76887fd5ccb3f51130a1389f634327f75c9ce4d7d149f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49ae1d575091ac7f9781b003ea1456f75f78d4a258fbe4af1f9d6a06add86d945422d7146fba6558c40e1230112c87db1238616eb180d307a1fcedb9c5360690", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/deee920bfd8cb2ab98a5dc1e465972ece09d29357b91db504e5e25c1b834ed032d165693b50fd10cd5d99b32e59afbc72710053559d8fa2c08eecf2deb02dec6"} 04:52:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:52:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:52:16 INFO - Contents: 04:52:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0c8ba4299c6274271dedff2547945216b2d7bcbf1cad1a01ad82d6a2a2d88ec0fdba2dc66cc3bb549d76887fd5ccb3f51130a1389f634327f75c9ce4d7d149f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49ae1d575091ac7f9781b003ea1456f75f78d4a258fbe4af1f9d6a06add86d945422d7146fba6558c40e1230112c87db1238616eb180d307a1fcedb9c5360690", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/deee920bfd8cb2ab98a5dc1e465972ece09d29357b91db504e5e25c1b834ed032d165693b50fd10cd5d99b32e59afbc72710053559d8fa2c08eecf2deb02dec6"} 04:52:16 INFO - Running post-run listener: copy_logs_to_upload_dir 04:52:16 INFO - Copying logs to upload dir... 04:52:16 INFO - mkdir: /builds/slave/test/build/upload/logs 04:52:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.638713 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-23 04:52:16.208787) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 04:52:16.211954) ========= 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=eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0c8ba4299c6274271dedff2547945216b2d7bcbf1cad1a01ad82d6a2a2d88ec0fdba2dc66cc3bb549d76887fd5ccb3f51130a1389f634327f75c9ce4d7d149f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49ae1d575091ac7f9781b003ea1456f75f78d4a258fbe4af1f9d6a06add86d945422d7146fba6558c40e1230112c87db1238616eb180d307a1fcedb9c5360690", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/deee920bfd8cb2ab98a5dc1e465972ece09d29357b91db504e5e25c1b834ed032d165693b50fd10cd5d99b32e59afbc72710053559d8fa2c08eecf2deb02dec6"} build_url:https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011551 build_url: 'https://queue.taskcluster.net/v1/task/AuDmYaVHRxyGcyLMy9dE4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0c8ba4299c6274271dedff2547945216b2d7bcbf1cad1a01ad82d6a2a2d88ec0fdba2dc66cc3bb549d76887fd5ccb3f51130a1389f634327f75c9ce4d7d149f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49ae1d575091ac7f9781b003ea1456f75f78d4a258fbe4af1f9d6a06add86d945422d7146fba6558c40e1230112c87db1238616eb180d307a1fcedb9c5360690", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/deee920bfd8cb2ab98a5dc1e465972ece09d29357b91db504e5e25c1b834ed032d165693b50fd10cd5d99b32e59afbc72710053559d8fa2c08eecf2deb02dec6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 04:52:16.238141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 04:52:16.238491) ========= 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=eca2ab57546de7b41f256b19000001ef-1466681492.885354-564900930 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 04:52:16.322808) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 04:52:16.323136) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 04:52:16.323451) ========= ========= Total master_lag: 0.17 =========