builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1466522699.4 results: success (0) buildid: 20160621055101 builduid: 7a91ac10c48d4c99a32a6c5c0d0f27ec revision: 6ed3514f726d04fd4481039d10af2694e8994636 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.403969) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.404471) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.404760) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.431589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.431897) ========= 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=d2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-21 08:25:00-- 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% 222M=0s 2016-06-21 08:25:00 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093897 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.542072) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.542450) ========= 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=d2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018555 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.592313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:24:59.592672) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ed3514f726d04fd4481039d10af2694e8994636 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ed3514f726d04fd4481039d10af2694e8994636 --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=d2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-21 08:25:00,763 truncating revision to first 12 chars 2016-06-21 08:25:00,763 Setting DEBUG logging. 2016-06-21 08:25:00,764 attempt 1/10 2016-06-21 08:25:00,764 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6ed3514f726d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-21 08:25:01,152 unpacking tar archive at: mozilla-aurora-6ed3514f726d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.729733 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:25:00.337041) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 08:25:00.337340) ========= script_repo_revision: 6ed3514f726d04fd4481039d10af2694e8994636 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 08:25:00.337711) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 08:25:00.337984) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 08:25:00.349109) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-06-21 08:25:00.349462) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=d2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:25:01 INFO - MultiFileLogger online at 20160621 08:25:01 in /builds/slave/test 08:25:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:25:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:25:01 INFO - {'append_to_log': False, 08:25:01 INFO - 'base_work_dir': '/builds/slave/test', 08:25:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:25:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:25:01 INFO - 'branch': 'Mozilla-Aurora', 08:25:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:25:01 INFO - 'config_files': ('talos/linux_config.py',), 08:25:01 INFO - 'default_actions': ('clobber', 08:25:01 INFO - 'read-buildbot-config', 08:25:01 INFO - 'download-and-extract', 08:25:01 INFO - 'populate-webroot', 08:25:01 INFO - 'create-virtualenv', 08:25:01 INFO - 'install', 08:25:01 INFO - 'run-tests'), 08:25:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:25:01 INFO - 'download_minidump_stackwalk': True, 08:25:01 INFO - 'download_symbols': 'ondemand', 08:25:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:25:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:25:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:25:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:25:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:25:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:25:01 INFO - 'installer_path': 'installer.exe', 08:25:01 INFO - 'log_level': 'info', 08:25:01 INFO - 'log_name': 'talos', 08:25:01 INFO - 'log_to_console': True, 08:25:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:25:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:25:01 INFO - 'opt_config_files': (), 08:25:01 INFO - 'pip_index': False, 08:25:01 INFO - 'sps_profile': False, 08:25:01 INFO - 'sps_profile_interval': 0, 08:25:01 INFO - 'suite': 'dromaeojs', 08:25:01 INFO - 'system_bits': '32', 08:25:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:25:01 INFO - 'title': 'talos-linux64-ix-043', 08:25:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:25:01 INFO - 'use_talos_json': True, 08:25:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:25:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:25:01 INFO - 'work_dir': 'build'} 08:25:01 INFO - ##### 08:25:01 INFO - ##### Running clobber step. 08:25:01 INFO - ##### 08:25:01 INFO - Running pre-action listener: _resource_record_pre_action 08:25:01 INFO - Running main action method: clobber 08:25:01 INFO - rmtree: /builds/slave/test/build 08:25:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:25:02 INFO - Running post-action listener: _resource_record_post_action 08:25:02 INFO - ##### 08:25:02 INFO - ##### Finished clobber step (success) 08:25:02 INFO - ##### 08:25:02 INFO - ##### 08:25:02 INFO - ##### Running read-buildbot-config step. 08:25:02 INFO - ##### 08:25:02 INFO - Running pre-action listener: _resource_record_pre_action 08:25:02 INFO - Running main action method: read_buildbot_config 08:25:02 INFO - Using buildbot properties: 08:25:02 INFO - { 08:25:02 INFO - "project": "", 08:25:02 INFO - "product": "firefox", 08:25:02 INFO - "script_repo_revision": "production", 08:25:02 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:25:02 INFO - "repository": "", 08:25:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:25:02 INFO - "buildid": "20160621055101", 08:25:02 INFO - "pgo_build": "True", 08:25:02 INFO - "basedir": "/builds/slave/test", 08:25:02 INFO - "buildnumber": 387, 08:25:02 INFO - "slavename": "talos-linux64-ix-043", 08:25:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:25:02 INFO - "platform": "ubuntu64_hw", 08:25:02 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:25:02 INFO - "branch": "mozilla-aurora", 08:25:02 INFO - "repo_path": "releases/mozilla-aurora", 08:25:02 INFO - "revision": "6ed3514f726d04fd4481039d10af2694e8994636", 08:25:02 INFO - "stage_platform": "linux64-pgo", 08:25:02 INFO - "builduid": "7a91ac10c48d4c99a32a6c5c0d0f27ec", 08:25:02 INFO - "slavebuilddir": "test" 08:25:02 INFO - } 08:25:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 08:25:02 INFO - Running post-action listener: _resource_record_post_action 08:25:02 INFO - ##### 08:25:02 INFO - ##### Finished read-buildbot-config step (success) 08:25:02 INFO - ##### 08:25:02 INFO - ##### 08:25:02 INFO - ##### Running download-and-extract step. 08:25:02 INFO - ##### 08:25:02 INFO - Running pre-action listener: _resource_record_pre_action 08:25:02 INFO - Running main action method: download_and_extract 08:25:02 INFO - mkdir: /builds/slave/test/build/tests 08:25: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')]} 08:25:02 INFO - https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:25:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:25:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:25:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:25:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:25:10 INFO - Downloaded 1361 bytes. 08:25:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:25:10 INFO - Using the following test package requirements: 08:25:10 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:25:10 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:25:10 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:25:10 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'jsshell-linux-x86_64.zip'], 08:25:10 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:25:10 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:25:10 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:25:10 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:25:10 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:25:10 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:25:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:25:10 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:25:10 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')]} 08:25:10 INFO - https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:25:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:25:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:25:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:25:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:25:13 INFO - Downloaded 24473683 bytes. 08:25:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:25:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:25:14 INFO - Return code: 0 08:25:14 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:25:14 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')]} 08:25:14 INFO - https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:25:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:25:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:25:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:25:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:25:17 INFO - Downloaded 24473683 bytes. 08:25:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:25:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:25:19 INFO - Return code: 0 08:25:19 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')]} 08:25:19 INFO - https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:25:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:25:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:25:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:25:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:25:20 INFO - Downloaded 10833369 bytes. 08:25:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:25:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:25:20 INFO - Return code: 0 08:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:25:20 INFO - https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:25:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:25:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:25:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:25:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:25:21 INFO - Downloaded 63598811 bytes. 08:25:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:25:21 INFO - mkdir: /builds/slave/test/properties 08:25:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:25:21 INFO - Writing to file /builds/slave/test/properties/build_url 08:25:21 INFO - Contents: 08:25:21 INFO - build_url:https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:25:22 INFO - Running post-action listener: _resource_record_post_action 08:25:22 INFO - Running post-action listener: set_extra_try_arguments 08:25:22 INFO - ##### 08:25:22 INFO - ##### Finished download-and-extract step (success) 08:25:22 INFO - ##### 08:25:22 INFO - ##### 08:25:22 INFO - ##### Running populate-webroot step. 08:25:22 INFO - ##### 08:25:22 INFO - Running pre-action listener: _resource_record_pre_action 08:25:22 INFO - Running main action method: populate_webroot 08:25:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:25:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:25:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:25:22 INFO - '--tppagecycles', 08:25:22 INFO - '7'], 08:25:22 INFO - 'tests': ['tsvgm']}}, 08:25:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:25:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:25:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:25:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:25:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:25:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:25:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['damp', 'tps']}, 08:25:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'tests': ['damp', 'tps']}, 08:25:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['dromaeo_dom']}, 08:25:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:25:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['a11yr', 08:25:22 INFO - 'ts_paint', 08:25:22 INFO - 'tpaint', 08:25:22 INFO - 'sessionrestore', 08:25:22 INFO - 'sessionrestore_no_auto_restore', 08:25:22 INFO - 'tabpaint']}, 08:25:22 INFO - 'other-e10s': {'tests': ['a11yr', 08:25:22 INFO - 'ts_paint', 08:25:22 INFO - 'tpaint', 08:25:22 INFO - 'sessionrestore', 08:25:22 INFO - 'sessionrestore_no_auto_restore', 08:25:22 INFO - 'tabpaint']}, 08:25:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:25:22 INFO - 'ts_paint', 08:25:22 INFO - 'tpaint', 08:25:22 INFO - 'sessionrestore', 08:25:22 INFO - 'sessionrestore_no_auto_restore', 08:25:22 INFO - 'tabpaint']}, 08:25:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:25:22 INFO - 'ts_paint', 08:25:22 INFO - 'tpaint', 08:25:22 INFO - 'sessionrestore', 08:25:22 INFO - 'sessionrestore_no_auto_restore', 08:25:22 INFO - 'tabpaint']}, 08:25:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['a11yr', 08:25:22 INFO - 'ts_paint', 08:25:22 INFO - 'tpaint', 08:25:22 INFO - 'sessionrestore', 08:25:22 INFO - 'sessionrestore_no_auto_restore', 08:25:22 INFO - 'tabpaint']}, 08:25:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['a11yr', 08:25:22 INFO - 'ts_paint', 08:25:22 INFO - 'tpaint', 08:25:22 INFO - 'sessionrestore', 08:25:22 INFO - 'sessionrestore_no_auto_restore', 08:25:22 INFO - 'tabpaint']}, 08:25:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['tsvgx', 08:25:22 INFO - 'tsvgr_opacity', 08:25:22 INFO - 'tart', 08:25:22 INFO - 'tscrollx', 08:25:22 INFO - 'cart']}, 08:25:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:25:22 INFO - 'tsvgr_opacity', 08:25:22 INFO - 'tart', 08:25:22 INFO - 'tscrollx', 08:25:22 INFO - 'cart']}, 08:25:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'talos_options': ['--disable-e10s'], 08:25:22 INFO - 'tests': ['tp5o']}, 08:25:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'tests': ['tp5o']}, 08:25:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'talos_options': ['--disable-e10s', 08:25:22 INFO - '--xperf_path', 08:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:25:22 INFO - 'tests': ['tp5n']}, 08:25:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:25:22 INFO - 'talos_options': ['--xperf_path', 08:25:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:25:22 INFO - 'tests': ['tp5n']}}, 08:25:22 INFO - 'talos.zip': {'path': '', 08:25:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:25:22 INFO - Running post-action listener: _resource_record_post_action 08:25:22 INFO - ##### 08:25:22 INFO - ##### Finished populate-webroot step (success) 08:25:22 INFO - ##### 08:25:22 INFO - ##### 08:25:22 INFO - ##### Running create-virtualenv step. 08:25:22 INFO - ##### 08:25:22 INFO - Running pre-action listener: _resource_record_pre_action 08:25:22 INFO - Running main action method: create_virtualenv 08:25:22 INFO - Creating virtualenv /builds/slave/test/build/venv 08:25:22 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 08:25:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:25:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:25:22 INFO - Using real prefix '/usr' 08:25:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:25:23 INFO - Installing distribute.............................................................................................................................................................................................done. 08:25:25 INFO - Installing pip.................done. 08:25:25 INFO - Return code: 0 08:25:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:25:25 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')]} 08:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:25 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')]} 08:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:25 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:25 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 08:25:25 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 08:25:25 INFO - Using env: {'DISPLAY': ':0', 08:25:25 INFO - 'HOME': '/home/cltbld', 08:25:25 INFO - 'LANG': 'en_US.UTF-8', 08:25:25 INFO - 'LANGUAGE': 'en_US:en', 08:25:25 INFO - 'LOGNAME': 'cltbld', 08:25:25 INFO - 'MAIL': '/var/mail/cltbld', 08:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:25 INFO - 'MOZ_NO_REMOTE': '1', 08:25:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:25 INFO - 'NO_EM_RESTART': '1', 08:25:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:25 INFO - 'PWD': '/builds/slave/test', 08:25:25 INFO - 'SHELL': '/bin/bash', 08:25:25 INFO - 'SHLVL': '1', 08:25:25 INFO - 'TERM': 'linux', 08:25:25 INFO - 'TMOUT': '86400', 08:25:25 INFO - 'USER': 'cltbld', 08:25:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:25 INFO - '_': '/tools/buildbot/bin/python'} 08:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:25 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 08:25:25 INFO - Cleaning up... 08:25:25 INFO - Return code: 0 08:25:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:25:25 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')]} 08:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:25 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')]} 08:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:25 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:25 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 08:25:25 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 08:25:25 INFO - Using env: {'DISPLAY': ':0', 08:25:25 INFO - 'HOME': '/home/cltbld', 08:25:25 INFO - 'LANG': 'en_US.UTF-8', 08:25:25 INFO - 'LANGUAGE': 'en_US:en', 08:25:25 INFO - 'LOGNAME': 'cltbld', 08:25:25 INFO - 'MAIL': '/var/mail/cltbld', 08:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:25 INFO - 'MOZ_NO_REMOTE': '1', 08:25:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:25 INFO - 'NO_EM_RESTART': '1', 08:25:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:25 INFO - 'PWD': '/builds/slave/test', 08:25:25 INFO - 'SHELL': '/bin/bash', 08:25:25 INFO - 'SHLVL': '1', 08:25:25 INFO - 'TERM': 'linux', 08:25:25 INFO - 'TMOUT': '86400', 08:25:25 INFO - 'USER': 'cltbld', 08:25:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:25 INFO - '_': '/tools/buildbot/bin/python'} 08:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:25 INFO - Downloading/unpacking psutil>=3.1.1 08:25:25 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 08:25:25 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 08:25:25 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 08:25:25 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 08:25:25 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 08:25:25 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 08:25:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:25:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:27 INFO - Installing collected packages: psutil 08:25:27 INFO - Running setup.py install for psutil 08:25:27 INFO - building 'psutil._psutil_linux' extension 08:25:27 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 08:25:27 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 08:25:27 INFO - building 'psutil._psutil_posix' extension 08:25:27 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 08:25:27 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 08:25:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:25:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:25:27 INFO - Successfully installed psutil 08:25:27 INFO - Cleaning up... 08:25:28 INFO - Return code: 0 08:25:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:25:28 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')]} 08:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:28 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')]} 08:25:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:28 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:28 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 08:25:28 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 08:25:28 INFO - Using env: {'DISPLAY': ':0', 08:25:28 INFO - 'HOME': '/home/cltbld', 08:25:28 INFO - 'LANG': 'en_US.UTF-8', 08:25:28 INFO - 'LANGUAGE': 'en_US:en', 08:25:28 INFO - 'LOGNAME': 'cltbld', 08:25:28 INFO - 'MAIL': '/var/mail/cltbld', 08:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:28 INFO - 'MOZ_NO_REMOTE': '1', 08:25:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:28 INFO - 'NO_EM_RESTART': '1', 08:25:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:28 INFO - 'PWD': '/builds/slave/test', 08:25:28 INFO - 'SHELL': '/bin/bash', 08:25:28 INFO - 'SHLVL': '1', 08:25:28 INFO - 'TERM': 'linux', 08:25:28 INFO - 'TMOUT': '86400', 08:25:28 INFO - 'USER': 'cltbld', 08:25:28 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:28 INFO - '_': '/tools/buildbot/bin/python'} 08:25:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 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 08:25:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:25:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:25:30 INFO - Installing collected packages: mozsystemmonitor 08:25:30 INFO - Running setup.py install for mozsystemmonitor 08:25:30 INFO - Successfully installed mozsystemmonitor 08:25:30 INFO - Cleaning up... 08:25:30 INFO - Return code: 0 08:25:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:25:30 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')]} 08:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:30 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')]} 08:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:30 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:30 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 08:25:30 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 08:25:30 INFO - Using env: {'DISPLAY': ':0', 08:25:30 INFO - 'HOME': '/home/cltbld', 08:25:30 INFO - 'LANG': 'en_US.UTF-8', 08:25:30 INFO - 'LANGUAGE': 'en_US:en', 08:25:30 INFO - 'LOGNAME': 'cltbld', 08:25:30 INFO - 'MAIL': '/var/mail/cltbld', 08:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:30 INFO - 'MOZ_NO_REMOTE': '1', 08:25:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:30 INFO - 'NO_EM_RESTART': '1', 08:25:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:30 INFO - 'PWD': '/builds/slave/test', 08:25:30 INFO - 'SHELL': '/bin/bash', 08:25:30 INFO - 'SHLVL': '1', 08:25:30 INFO - 'TERM': 'linux', 08:25:30 INFO - 'TMOUT': '86400', 08:25:30 INFO - 'USER': 'cltbld', 08:25:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:30 INFO - '_': '/tools/buildbot/bin/python'} 08:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:30 INFO - Downloading/unpacking jsonschema==2.5.1 08:25:30 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 08:25:30 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 08:25:30 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 08:25:30 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 08:25:30 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 08:25:30 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 08:25:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:25:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:25:32 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 08:25:32 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 08:25:32 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 08:25:32 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 08:25:32 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 08:25:32 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 08:25:32 INFO - Downloading functools32-3.2.3-2.tar.gz 08:25:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:25:32 INFO - warning: no files found matching '*.txt' 08:25:32 INFO - no previously-included directories found matching 'build' 08:25:32 INFO - no previously-included directories found matching 'dist' 08:25:32 INFO - no previously-included directories found matching '.git*' 08:25:32 INFO - Installing collected packages: jsonschema, functools32 08:25:32 INFO - Running setup.py install for functools32 08:25:32 INFO - warning: no files found matching '*.txt' 08:25:32 INFO - no previously-included directories found matching 'build' 08:25:32 INFO - no previously-included directories found matching 'dist' 08:25:32 INFO - no previously-included directories found matching '.git*' 08:25:32 INFO - Successfully installed jsonschema functools32 08:25:32 INFO - Cleaning up... 08:25:32 INFO - Return code: 0 08:25:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:25:32 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')]} 08:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:32 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')]} 08:25:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:32 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:32 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 08:25:32 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 08:25:32 INFO - Using env: {'DISPLAY': ':0', 08:25:32 INFO - 'HOME': '/home/cltbld', 08:25:32 INFO - 'LANG': 'en_US.UTF-8', 08:25:32 INFO - 'LANGUAGE': 'en_US:en', 08:25:32 INFO - 'LOGNAME': 'cltbld', 08:25:32 INFO - 'MAIL': '/var/mail/cltbld', 08:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:32 INFO - 'MOZ_NO_REMOTE': '1', 08:25:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:32 INFO - 'NO_EM_RESTART': '1', 08:25:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:32 INFO - 'PWD': '/builds/slave/test', 08:25:32 INFO - 'SHELL': '/bin/bash', 08:25:32 INFO - 'SHLVL': '1', 08:25:32 INFO - 'TERM': 'linux', 08:25:32 INFO - 'TMOUT': '86400', 08:25:32 INFO - 'USER': 'cltbld', 08:25:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:32 INFO - '_': '/tools/buildbot/bin/python'} 08:25:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:33 INFO - Downloading/unpacking blobuploader==1.2.4 08:25:33 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 08:25:33 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 08:25:33 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 08:25:33 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 08:25:33 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 08:25:33 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 08:25:34 INFO - Downloading blobuploader-1.2.4.tar.gz 08:25:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:25:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:25:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 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 08:25:35 INFO - Downloading docopt-0.6.1.tar.gz 08:25:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:25:35 INFO - Installing collected packages: blobuploader, requests, docopt 08:25:35 INFO - Running setup.py install for blobuploader 08:25:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:25:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:25:35 INFO - Running setup.py install for requests 08:25:36 INFO - Running setup.py install for docopt 08:25:36 INFO - Successfully installed blobuploader requests docopt 08:25:36 INFO - Cleaning up... 08:25:36 INFO - Return code: 0 08:25:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:36 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:36 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 08:25:36 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 08:25:36 INFO - Using env: {'DISPLAY': ':0', 08:25:36 INFO - 'HOME': '/home/cltbld', 08:25:36 INFO - 'LANG': 'en_US.UTF-8', 08:25:36 INFO - 'LANGUAGE': 'en_US:en', 08:25:36 INFO - 'LOGNAME': 'cltbld', 08:25:36 INFO - 'MAIL': '/var/mail/cltbld', 08:25:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:36 INFO - 'MOZ_NO_REMOTE': '1', 08:25:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:36 INFO - 'NO_EM_RESTART': '1', 08:25:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:36 INFO - 'PWD': '/builds/slave/test', 08:25:36 INFO - 'SHELL': '/bin/bash', 08:25:36 INFO - 'SHLVL': '1', 08:25:36 INFO - 'TERM': 'linux', 08:25:36 INFO - 'TMOUT': '86400', 08:25:36 INFO - 'USER': 'cltbld', 08:25:36 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:36 INFO - '_': '/tools/buildbot/bin/python'} 08:25:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:36 INFO - Running setup.py (path:/tmp/pip-PjEe3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:36 INFO - Running setup.py (path:/tmp/pip-MK7tF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:36 INFO - Running setup.py (path:/tmp/pip-DPnktc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:36 INFO - Running setup.py (path:/tmp/pip-NxpwAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:36 INFO - Running setup.py (path:/tmp/pip-DmlCtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:36 INFO - Running setup.py (path:/tmp/pip-QuWTZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:37 INFO - Running setup.py (path:/tmp/pip-S6r4Hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:37 INFO - Running setup.py (path:/tmp/pip-0ZGLg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:37 INFO - Running setup.py (path:/tmp/pip-qFnhq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:37 INFO - Running setup.py (path:/tmp/pip-lq3H0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:37 INFO - Running setup.py (path:/tmp/pip-qT039o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:37 INFO - Running setup.py (path:/tmp/pip-gHhb9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:37 INFO - Running setup.py (path:/tmp/pip-l5dh46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:37 INFO - Running setup.py (path:/tmp/pip-3y0BBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:37 INFO - Running setup.py (path:/tmp/pip-7nyN_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:37 INFO - Running setup.py (path:/tmp/pip-OH7Fgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:37 INFO - Running setup.py (path:/tmp/pip-iSnfUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:25:37 INFO - Running setup.py install for manifestparser 08:25:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:25:37 INFO - Running setup.py install for mozcrash 08:25:38 INFO - Running setup.py install for mozdebug 08:25:38 INFO - Running setup.py install for mozdevice 08:25:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for mozfile 08:25:38 INFO - Running setup.py install for mozhttpd 08:25:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for mozinfo 08:25:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for mozInstall 08:25:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for mozleak 08:25:38 INFO - Running setup.py install for mozlog 08:25:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for moznetwork 08:25:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:25:38 INFO - Running setup.py install for mozprocess 08:25:39 INFO - Running setup.py install for mozprofile 08:25:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:25:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:25:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:25:39 INFO - Running setup.py install for mozrunner 08:25:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:25:39 INFO - Running setup.py install for mozscreenshot 08:25:39 INFO - Running setup.py install for moztest 08:25:39 INFO - Running setup.py install for mozversion 08:25:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:25:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:25:39 INFO - Cleaning up... 08:25:39 INFO - Return code: 0 08:25:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:39 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:39 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 08:25:39 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 08:25:39 INFO - Using env: {'DISPLAY': ':0', 08:25:39 INFO - 'HOME': '/home/cltbld', 08:25:39 INFO - 'LANG': 'en_US.UTF-8', 08:25:39 INFO - 'LANGUAGE': 'en_US:en', 08:25:39 INFO - 'LOGNAME': 'cltbld', 08:25:39 INFO - 'MAIL': '/var/mail/cltbld', 08:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:39 INFO - 'MOZ_NO_REMOTE': '1', 08:25:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:39 INFO - 'NO_EM_RESTART': '1', 08:25:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:39 INFO - 'PWD': '/builds/slave/test', 08:25:39 INFO - 'SHELL': '/bin/bash', 08:25:39 INFO - 'SHLVL': '1', 08:25:39 INFO - 'TERM': 'linux', 08:25:39 INFO - 'TMOUT': '86400', 08:25:39 INFO - 'USER': 'cltbld', 08:25:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:39 INFO - '_': '/tools/buildbot/bin/python'} 08:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:39 INFO - Running setup.py (path:/tmp/pip-RfSB8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:39 INFO - Running setup.py (path:/tmp/pip-V28daX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:39 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)) 08:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:39 INFO - Running setup.py (path:/tmp/pip-mPCeSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:40 INFO - Running setup.py (path:/tmp/pip-mp6yDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:40 INFO - Running setup.py (path:/tmp/pip-Ocihs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:40 INFO - Running setup.py (path:/tmp/pip-gr6OKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:40 INFO - Running setup.py (path:/tmp/pip-_ilbsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:40 INFO - Running setup.py (path:/tmp/pip-t8LRVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:40 INFO - Running setup.py (path:/tmp/pip-Nwzaam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:40 INFO - Running setup.py (path:/tmp/pip-y48s8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:40 INFO - Running setup.py (path:/tmp/pip-yQo33B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:40 INFO - Running setup.py (path:/tmp/pip-vXlLVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:40 INFO - Running setup.py (path:/tmp/pip-hSU19s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:40 INFO - Running setup.py (path:/tmp/pip-8oVCAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:40 INFO - Running setup.py (path:/tmp/pip-nY5aq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:40 INFO - Running setup.py (path:/tmp/pip-iMZU1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:40 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)) 08:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:41 INFO - Running setup.py (path:/tmp/pip-oBg2sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:41 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)) 08:25:41 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)) 08:25:41 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)) 08:25:41 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)) 08:25:41 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)) 08:25:41 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)) 08:25:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:25:41 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 08:25:41 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 08:25:41 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 08:25:41 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 08:25:41 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 08:25:41 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 08:25:42 INFO - Downloading blessings-1.6.tar.gz 08:25:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:25:43 INFO - Installing collected packages: blessings 08:25:43 INFO - Running setup.py install for blessings 08:25:43 INFO - Successfully installed blessings 08:25:43 INFO - Cleaning up... 08:25:43 INFO - Return code: 0 08:25:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:25:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:43 INFO - Reading from file tmpfile_stdout 08:25:43 INFO - Current package versions: 08:25:43 INFO - argparse == 1.2.1 08:25:43 INFO - blessings == 1.6 08:25:43 INFO - blobuploader == 1.2.4 08:25:43 INFO - docopt == 0.6.1 08:25:43 INFO - functools32 == 3.2.3-2 08:25:43 INFO - jsonschema == 2.5.1 08:25:43 INFO - manifestparser == 1.1 08:25:43 INFO - mozInstall == 1.12 08:25:43 INFO - mozcrash == 0.17 08:25:43 INFO - mozdebug == 0.1 08:25:43 INFO - mozdevice == 0.48 08:25:43 INFO - mozfile == 1.2 08:25:43 INFO - mozhttpd == 0.7 08:25:43 INFO - mozinfo == 0.9 08:25:43 INFO - mozleak == 0.1 08:25:43 INFO - mozlog == 3.2 08:25:43 INFO - moznetwork == 0.27 08:25:43 INFO - mozprocess == 0.22 08:25:43 INFO - mozprofile == 0.28 08:25:43 INFO - mozrunner == 6.11 08:25:43 INFO - mozscreenshot == 0.1 08:25:43 INFO - mozsystemmonitor == 0.3 08:25:43 INFO - moztest == 0.7 08:25:43 INFO - mozversion == 1.4 08:25:43 INFO - psutil == 3.1.1 08:25:43 INFO - requests == 1.2.3 08:25:43 INFO - wsgiref == 0.1.2 08:25:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:43 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:43 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 08:25:43 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 08:25:43 INFO - Using env: {'DISPLAY': ':0', 08:25:43 INFO - 'HOME': '/home/cltbld', 08:25:43 INFO - 'LANG': 'en_US.UTF-8', 08:25:43 INFO - 'LANGUAGE': 'en_US:en', 08:25:43 INFO - 'LOGNAME': 'cltbld', 08:25:43 INFO - 'MAIL': '/var/mail/cltbld', 08:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:43 INFO - 'MOZ_NO_REMOTE': '1', 08:25:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:43 INFO - 'NO_EM_RESTART': '1', 08:25:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:43 INFO - 'PWD': '/builds/slave/test', 08:25:43 INFO - 'SHELL': '/bin/bash', 08:25:43 INFO - 'SHLVL': '1', 08:25:43 INFO - 'TERM': 'linux', 08:25:43 INFO - 'TMOUT': '86400', 08:25:43 INFO - 'USER': 'cltbld', 08:25:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:43 INFO - '_': '/tools/buildbot/bin/python'} 08:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 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)) 08:25:43 INFO - Cleaning up... 08:25:43 INFO - Return code: 0 08:25:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:43 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:43 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 08:25:43 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 08:25:43 INFO - Using env: {'DISPLAY': ':0', 08:25:43 INFO - 'HOME': '/home/cltbld', 08:25:43 INFO - 'LANG': 'en_US.UTF-8', 08:25:43 INFO - 'LANGUAGE': 'en_US:en', 08:25:43 INFO - 'LOGNAME': 'cltbld', 08:25:43 INFO - 'MAIL': '/var/mail/cltbld', 08:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:43 INFO - 'MOZ_NO_REMOTE': '1', 08:25:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:43 INFO - 'NO_EM_RESTART': '1', 08:25:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:43 INFO - 'PWD': '/builds/slave/test', 08:25:43 INFO - 'SHELL': '/bin/bash', 08:25:43 INFO - 'SHLVL': '1', 08:25:43 INFO - 'TERM': 'linux', 08:25:43 INFO - 'TMOUT': '86400', 08:25:43 INFO - 'USER': 'cltbld', 08:25:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:43 INFO - '_': '/tools/buildbot/bin/python'} 08:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:25:43 INFO - Cleaning up... 08:25:43 INFO - Return code: 0 08:25:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:25: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')]} 08:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25: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')]} 08:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:25:43 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 0x7f37adc42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', '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 08:25:43 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 08:25:43 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 08:25:43 INFO - Using env: {'DISPLAY': ':0', 08:25:43 INFO - 'HOME': '/home/cltbld', 08:25:43 INFO - 'LANG': 'en_US.UTF-8', 08:25:43 INFO - 'LANGUAGE': 'en_US:en', 08:25:43 INFO - 'LOGNAME': 'cltbld', 08:25:43 INFO - 'MAIL': '/var/mail/cltbld', 08:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:43 INFO - 'MOZ_NO_REMOTE': '1', 08:25:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:43 INFO - 'NO_EM_RESTART': '1', 08:25:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:43 INFO - 'PWD': '/builds/slave/test', 08:25:43 INFO - 'SHELL': '/bin/bash', 08:25:43 INFO - 'SHLVL': '1', 08:25:43 INFO - 'TERM': 'linux', 08:25:43 INFO - 'TMOUT': '86400', 08:25:43 INFO - 'USER': 'cltbld', 08:25:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:43 INFO - '_': '/tools/buildbot/bin/python'} 08:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:44 INFO - Downloading/unpacking flake8 08:25:44 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 08:25:44 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 08:25:44 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 08:25:44 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 08:25:44 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 08:25:44 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 08:25:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:25:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:25:45 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 08:25:45 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 08:25:45 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 08:25:45 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 08:25:45 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 08:25:45 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 08:25:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:25:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 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 08:25:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:25:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:25:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:25:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:25:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:25:46 INFO - no previously-included directories found matching 'docs/_build' 08:25:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:25:46 INFO - Running setup.py install for pep8 08:25:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:25:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:25:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:25:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:25:46 INFO - no previously-included directories found matching 'docs/_build' 08:25:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:25:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:25:46 INFO - Cleaning up... 08:25:46 INFO - Return code: 0 08:25:46 INFO - Running post-action listener: _resource_record_post_action 08:25:46 INFO - Running post-action listener: _start_resource_monitoring 08:25:46 INFO - Starting resource monitoring. 08:25:46 INFO - ##### 08:25:46 INFO - ##### Finished create-virtualenv step (success) 08:25:46 INFO - ##### 08:25:46 INFO - ##### 08:25:46 INFO - ##### Running install step. 08:25:46 INFO - ##### 08:25:46 INFO - Running pre-action listener: _resource_record_pre_action 08:25:46 INFO - Running main action method: install 08:25:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:47 INFO - Reading from file tmpfile_stdout 08:25:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:25:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:25:47 INFO - Reading from file tmpfile_stdout 08:25:47 INFO - Output received: 08:25:47 INFO - Usage: mozinstall [options] installer 08:25:47 INFO - Options: 08:25:47 INFO - -h, --help show this help message and exit 08:25:47 INFO - -d DEST, --destination=DEST 08:25:47 INFO - Directory to install application into. [default: 08:25:47 INFO - "/builds/slave/test"] 08:25:47 INFO - --app=APP Application being installed. [default: firefox] 08:25:47 INFO - mkdir: /builds/slave/test/build/application 08:25:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:26:03 INFO - Reading from file tmpfile_stdout 08:26:03 INFO - Output received: 08:26:03 INFO - /builds/slave/test/build/application/firefox/firefox 08:26:03 INFO - Running post-action listener: _resource_record_post_action 08:26:03 INFO - ##### 08:26:03 INFO - ##### Finished install step (success) 08:26:03 INFO - ##### 08:26:03 INFO - ##### 08:26:03 INFO - ##### Running run-tests step. 08:26:03 INFO - ##### 08:26:03 INFO - Running pre-action listener: _resource_record_pre_action 08:26:03 INFO - Running main action method: run_tests 08:26:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:26:03 INFO - Python 2.7.3 08:26:03 INFO - Return code: 0 08:26:03 INFO - grabbing minidump binary from tooltool 08:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:03 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 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:26:03 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 08:26:03 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 08:26:03 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 08:26:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:26:03 INFO - Return code: 0 08:26:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:26:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:26:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:26:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:26:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:26:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:26:04 INFO - Return code: 0 08:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:26:04 INFO - Using env: {'DISPLAY': ':0', 08:26:04 INFO - 'HOME': '/home/cltbld', 08:26:04 INFO - 'LANG': 'en_US.UTF-8', 08:26:04 INFO - 'LANGUAGE': 'en_US:en', 08:26:04 INFO - 'LOGNAME': 'cltbld', 08:26:04 INFO - 'MAIL': '/var/mail/cltbld', 08:26:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:26:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:04 INFO - 'MOZ_NO_REMOTE': '1', 08:26:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:26:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:26:04 INFO - 'NO_EM_RESTART': '1', 08:26:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:04 INFO - 'PWD': '/builds/slave/test', 08:26:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:26:04 INFO - 'SHELL': '/bin/bash', 08:26:04 INFO - 'SHLVL': '1', 08:26:04 INFO - 'TERM': 'linux', 08:26:04 INFO - 'TMOUT': '86400', 08:26:04 INFO - 'USER': 'cltbld', 08:26:04 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926', 08:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:04 INFO - '_': '/tools/buildbot/bin/python'} 08:26:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:26:04 INFO - mozversion application_buildid: 20160621055101 08:26:04 INFO - mozversion application_changeset: 6ed3514f726d04fd4481039d10af2694e8994636 08:26:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:26:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:26:04 INFO - mozversion application_name: Firefox 08:26:04 INFO - mozversion application_remotingname: firefox-dev 08:26:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:26:04 INFO - mozversion application_vendor: Mozilla 08:26:04 INFO - mozversion application_version: 49.0a2 08:26:04 INFO - mozversion platform_buildid: 20160621055101 08:26:04 INFO - mozversion platform_changeset: 6ed3514f726d04fd4481039d10af2694e8994636 08:26:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:26:04 INFO - mozversion platform_version: 49.0a2 08:26:04 INFO - using testdate: 1466522764 08:26:04 INFO - actual date: 1466522764 08:26:04 INFO - starting webserver on 'localhost:59192' 08:26:04 INFO - SUITE-START | Running 2 tests 08:26:04 INFO - TEST-START | dromaeo_css 08:26:04 INFO - operating with platform_type : linux_ 08:26:04 INFO - Initialising browser for dromaeo_css test... 08:26:04 INFO - TEST-INFO | started process 5952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8JDjn6/profile http://localhost:59192/getInfo.html) 08:26:08 INFO - PROCESS | 5952 | __metrics Screen width/height:1600/1200 08:26:08 INFO - PROCESS | 5952 | colorDepth:24 08:26:08 INFO - PROCESS | 5952 | Browser inner width/height: 1024/697 08:26:08 INFO - PROCESS | 5952 | __metrics 08:26:09 ERROR - PROCESS | 5952 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:26:09 INFO - PROCESS | 5952 | console.error: PushService: 08:26:09 INFO - PROCESS | 5952 | stateChangeProcessEnqueue: Error transitioning state 08:26:09 INFO - PROCESS | 5952 | UnknownError 08:26:10 INFO - TEST-INFO | 5952: exit 0 08:26:10 INFO - Browser initialized. 08:26:10 INFO - Running cycle 1/1 for dromaeo_css test... 08:26:10 INFO - TEST-INFO | started process 6047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8JDjn6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:26:22 INFO - PROCESS | 6047 | RSS: Main: 145494016 08:26:22 INFO - PROCESS | 6047 | 08:27:13 INFO - PROCESS | 6047 | 1466522833742 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] 08:29:21 INFO - PROCESS | 6047 | Cycle 1(1): loaded http://localhost:59192/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59192/tests/dromaeo/cssquery-ext.html) 08:29:22 INFO - PROCESS | 6047 | RSS: Main: 260325376 08:29:22 INFO - PROCESS | 6047 | 08:32:25 INFO - PROCESS | 6047 | Cycle 1(1): loaded http://localhost:59192/tests/dromaeo/cssquery-ext.html (next: http://localhost:59192/tests/dromaeo/cssquery-jquery.html) 08:32:25 INFO - PROCESS | 6047 | RSS: Main: 225746944 08:32:25 INFO - PROCESS | 6047 | 08:35:27 INFO - PROCESS | 6047 | Cycle 1(1): loaded http://localhost:59192/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59192/tests/dromaeo/cssquery-mootools.html) 08:35:28 INFO - PROCESS | 6047 | RSS: Main: 230481920 08:35:28 INFO - PROCESS | 6047 | 08:38:30 INFO - PROCESS | 6047 | Cycle 1(1): loaded http://localhost:59192/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59192/tests/dromaeo/cssquery-prototype.html) 08:38:30 INFO - PROCESS | 6047 | RSS: Main: 218746880 08:38:30 INFO - PROCESS | 6047 | 08:41:33 INFO - PROCESS | 6047 | Cycle 1(1): loaded http://localhost:59192/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59192/tests/dromaeo/cssquery-yui.html) 08:41:34 INFO - PROCESS | 6047 | RSS: Main: 218451968 08:41:34 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | Cycle 1(1): loaded http://localhost:59192/tests/dromaeo/cssquery-yui.html (next: http://localhost:59192/tests/dromaeo/cssquery-dojo.html) 08:44:36 INFO - PROCESS | 6047 | RSS: Main: 255365120 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | __start_tp_report 08:44:36 INFO - PROCESS | 6047 | _x_x_mozilla_page_load 08:44:36 INFO - PROCESS | 6047 | _x_x_mozilla_page_load_details 08:44:36 INFO - PROCESS | 6047 | |i|pagename|runs| 08:44:36 INFO - PROCESS | 6047 | |0;dojo.html;3397.065806903102;3397.9150521236975;3403.7447191460633;3431.5882094148706;3454.55090838191;5678.48893599576;5709.371969083398;5745.253117094782;5762.654240745553;5763.0779075347955;5675.1203563447625;5736.282964629421;5737.282839645044;5748.02283611786;5748.5401167906575;5060.898782024357;5171;5218.530332270095;5237.083510385686;5279.894402111955;5068.8225912093085;5182.404023537304;5217.295665085222;5219.217117432378;5220.086484865153;5121.385433747955;5145.459726728695;5158.845234642967;5192.558632516222;5202.947970520284;5259.368875734898;5268.762905669216;5269.65747226431;5271.130263506517;5306.416294207634;5016.924746128811;5018.7490625468945;5122.974385128051;5155.200943853709;5162.741862906877;5485.314335708036;5541.141123125922;5549.861253468695;5550.0564903966015;5601.383847776782;6064.575479716422;6091.228630771719;6092.878142437126;6095.177469327658;6120.234970628671;6085.4523092921845;6095.605389230519;6120.721420533214;6146.7233974471255;6177.980633195517;5739.827805165852;5760.711964401763;5782.537397008229;5793.188953546507;5799.246098007275;6075.422834830684;6319.905201421982;6328.936710632835;6333.366663333722;6335.524835637346;5918.911216331759;5952.583319167703;5992.910106348409;6041.486473649701;6068.21113255274;6103.6032657877095;6113.083037544404;6115.662097952266;6119.082137679384;6142.78500252489;5856.560757943172;5948.315943666534;6063.302720187235;6091.116788065706;6099.786507472217;5990.460858522754;6072.7570897164505;6086.239220097488;6086.513078953673;6100.755969761249;4607.262837945913;4616.307553866947;4621.19129152397;4622.214223581932;4623.190941585276;4019.618136277049;4035.6367926886132;4072.165206132736;4131.132462182975;4150.460440142762;5705.229793977843;5726.055200891889;5730.19777231188;5737.311522617312;5744.942550574441;5985;6049.606775559574;6049.8487537811025;6059.606125601822;6078.057901025348;5640.717964101778;5736.024875771045;5778.479936805624;5789.189513468118;5829.387914269026;5129.102407078746;5160.071187186342;5168.379794424618;5182.740862956837;5187.818426355059;6110.5722599418505;6390.137331460286;6402.295747467775;6408.743650254031;6421.197350331208;6066.817995460143;6123.571350005457;6124.216183935432;6138.324784273816;6166.938330616636;6353.745850165941;6371.331010243857;6374.808755737336;6375.234971803413;6391.840203994937;5828.475437210672;5998.760049597967;6060.636361818305;6061.393860613904;6061.969690151521;6114.5414093942245;6116.214037636738;6157.291911430243;6157.568970172045;6159.6612186330185;6001.039833626599;6001.609895356788;6002.249718785152;6007.639541627516;6034.607750496204;5761.049426844524;5886.823395298148;5905.143754155709;5933.2880054393745;6011.939880601138;5855.443732845373;5868.559858010581;5872.588918775645;5876.236089308362;5887.381824908323;6031.336552979256;6031.36670649593;6046.382115438178;6078.848028799315;6081.057436097412;5701.885962280814;5709.914351284651;5740.655560666373;5777.017906955912;5955 08:44:36 INFO - PROCESS | 6047 | |1;ext.html;595.1697382151898;639.2201514152733;653.9673016349182;657.707320242492;665.7436886798587;37165.628343716555;37935.620643793554;51062.999999999956;52662.473375266236;52663.21005184925;49615;50268.49731502684;50395;50444.495555044436;50459.747701261535;9194.770130746718;9341.43951362917;9342.57958391874;9351.76620584484;9356.672516461926;8423.15768423159;10865.130789536857;11120.110391168688;11125.054370378528;11128.109751219883;10848.403337816417;10950.726231844228;10956.068734157607;11273.661790146309;11283.943580282086;8382.20369064944;8583.05586385497;11125.332480051224;11203.943980280128;11229.775404491946;10654.520546575422;10986.615468458565;11020.173486988508;11373.431328433546;11395.54417823286;46809.53190468086;47330.52669473296;47357.52642473565;47375.28937065947;47452.76273618644;3871.728978971473;3948.309045916953;4476.306172543228;4505.909881802379;4575.702579332333;35690;36285.27429451422;36422.089447763756;36496.27007459836;36639.267214655825;4732.574068333832;4737.1946769049;4780.306855505932;4809.831655892062;4888.828890988798;364569;367575;367837.1608141969;368341;368722;36604.81697591495;37436.43845342322;70047.64976175087;71274.64362678153;71437.64281178561;39767.60232397639;39843.80078099649;39859.60140398617;39935.80032099821;39951.20097598032;17943.371981980566;17983.910080449514;17991.460256192335;17999.460016199537;18002.819971800116;13128.60614181576;14142.22217778032;14148.717025659429;14458.132512049311;14543.127412355292;9373.437593744397;9376.109269619375;9380.624775009059;9436.528173591292;9445.433274003582;3460.2387474755515;3508.19311558343;3556.9725256604006;3558.9644103558635;3580.964190358063;1073.6617965340893;1076.1821016027875;1150.5627861412609;1197.7663007102524;1200.7478429529897;34564.82717586446;35132.82433587867;35278;35332.47001294982;35434.822825885705;1157.7337212441187;1202.729385888168;1215.064400411678;1218.7684339975553;1266.65167079054;17911.193996270198;18306;18367.81632183688;18441.81558184401;18549.536261593297;170864;170880;171335;171612.70968225895;171960;66429.66785166043;66929;67070;67103;67146.66426667836;1272.4464857786893;1392.303848075962;1416.5679467762432;1465.5530063330573;1490.8881833862288;33129.337413252084;34136.31727365489;34168;34365;35210.8239458801;56839.71580142073;57534.424655752904;57572.13641795293;57678;57753;435166;443650;443778;443912;444265;28476.715232848233;30825.537616935315;31537.21156971094;32257;33401.49897751584;51971.48028519817;53497.46502534925;53527;54167.18389235054;54725.72637136789;44492;45660.77169614131;45984;45986;46971.53028469672;43636.345454817565;44404.777976109915;44659.77670111629;44991.77504112459;46139.76930115328;44123.11753764971;45090.77454612706;45210.321845173006;45451.545484546055;45658;38273.61726382701;39403.80298098491;39754.80122599369;40177.1964560713;40510.18979620332;29278;29614.15642631182;29956.400871982874;30163.396732064797;30181.547276790425 08:44:36 INFO - PROCESS | 6047 | |2;jquery.html;424.929886568716;427.42297897837915;429.74644959473903;429.78081178598904;441.6643351053199;18708.53228669284;19468.51328716781;20585.970701464925;20615.793842061576;20634.587308253827;19056;19656.11547480363;19739.1117399717;19896.602067958633;19919.900400498012;5729.6562206267545;5958.314793798725;5983.58114931955;5988.041913293874;5991.191189189469;5718.34239062509;5867.677277749722;5876.2067120938555;5908.61593996391;6024.1566180734735;6074.514038876901;6098.4511393974535;6121.602095863777;6121.908171377433;6124;5949.762009519614;5979.581429299951;6094.146819445281;6101.054336577838;6102.993006154002;5809.302883653946;5826.796062137847;5862.355140934494;5870.911936320958;5881.323647780518;14140.434382624682;14674.119552826865;14865.851341486556;14904.62738431537;14919.552413427615;2432.440538676097;2459.754024597544;2582.1349847801007;2809.6909339972585;2861.670907845604;10307.690769276935;11054.170937179744;11168.553257869757;11216;11860.406979650983;1896.2699360746074;2008.9095990680307;2046.1508473983279;2067.379786064175;2109.8523103382618;85958.57020714856;88702;89925.10074899232;90624;90694.99999999935;11691.707707307385;11709.590164344207;11873.346965916957;11991.520339186334;12035.338056406807;16739.91630041842;16761.32954681799;16811;16874.15629218534;16890.239939202766;8282.8343433131;8325;8498.150184981452;8596.613152408157;8822.647094116292;7901.960490197627;8165.95917020423;8575.656973720981;8715.520646364384;8817.29461643067;5030.673006254582;5047.596192304607;5077.543021128043;5096.464871188472;5100.897982040338;2016.354766474714;2030.1777779999125;2055.8869262190724;2057.4753437873546;2074.3984244569206;922.5156792683895;1016.9491525423699;1030.9381437113798;1052.8736551613856;1068.188176985481;19402.902985484983;19977.500562486053;19989.20043198285;20021.199152033754;20299.594008119755;989.6684610655511;998.4109375468508;1021.5607288865859;1035.746242170673;1041.3907863899703;10741.140708743438;11160.16298777595;11163.106951444028;11271.718207044725;11328.660140195807;473932;479086;486226;491019;504493;100861;101507;101844;102335.48832255791;102622;1033.565902321027;1035.7514196592763;1036.409246729357;1050.2385770316612;1054.1408751867205;17632.82367176312;17765.911170444582;17927.910360448113;17950.371736989142;18328.450146495627;30978.69021309758;40737.79631101825;41720;42768.78615606902;42785.14429711451;60275.69862150837;60671.39328606657;61224.693876530335;61516.38483615107;61837.381626184964;8584.055753867195;8734.956325218587;8760.386772925835;8943.284537237136;9057.501837399006;12286.754264914473;13143.408546615234;13503.59489215325;13571.389287482285;13620.591382258548;9319.30105242096;11652.94173529127;11926.821097683714;12247.571335003233;12250.877491224974;11928.224665396721;12006.21959572625;12031.699207519532;12068.637940861789;12358.258504489759;12120.878791212328;12189.329586872807;12239.20445171061;12295.200811947196;12303.200291980993;7945.6027198640995;8215.233948861553;8607.18231767981;8941;9322.533873306173;11204.43977801123;11806.35065071395;11812.291262524275;11887.702807429883;12532.93733531363 08:44:36 INFO - PROCESS | 6047 | |3;mootools.html;1415.9292035398228;1520.779486974389;1561.7891584636075;1746.257840417822;1874.4376686993899;8984.19142277195;9321.161095501404;9330.066993300676;9332.813343733122;9616.759581010478;8980.730578082652;9229.953850230739;9534.666286679967;9538.570764315622;9547.904520954788;2135.1566131378095;2186.1146235774536;2487.825852190347;2523.6719226500577;2577.7680008799202;2586.5602847515875;2622.7901767858525;2623.2392606144194;2624.9212523624324;2629.5792673172296;2525.5832787590025;2564.525562770884;2583.4962182374443;2583.560794664902;2585.987070064647;2132.9040193191267;2430.2952143878256;2455.7421470745494;2502.5370306493355;2511.535365957304;2506.035176457059;2512.673352464168;2518.5214809186377;2533.227365653475;2539.911103111382;9486.003969583164;9547.474888881108;9549.522523873848;9552.808943821085;9595.28035397348;5659.547236221134;5866.325372582122;5875.8531036724;5876.9118463223085;5877.823665290048;3662.5788034376124;3664.8900532984053;3791.4502397152532;3865.768053916774;3880.3985382265796;1269.7841366967639;1285.5372066056211;1290.986575538198;1314.2180402660401;1336.565616174747;13523.32383380827;13859.37632806516;13869.653258668513;13896.374663140083;13896.861031389657;5111.5655169310285;5118.564921981673;5140.40885298188;5214.765335559909;5232.319798426181;11634.70913227176;11648.94175529117;11661.475233614508;11662.94168529169;11667.183297169116;5785.537157027427;5785.71071446426;5786.276715410574;5786.334571524244;5787.826365209051;4719.221328480762;5242.868928276747;5336.599755018389;5349;5444.8094316698725;4440.2007638625355;4443.266860967904;4477.28363461851;4487.910241795211;4505.36924830524;1371.6228037289825;1418.4680744720729;1418.7375335562954;1421.985780142206;1427.5503216486982;994.2692121290845;1064.776396956648;1074.6185104287865;1088.357868857362;1106.5684383090602;6630.204375474974;6731.461483081342;6794.252632210453;6817.181938167352;6867.450603951672;503.18735242582966;505.1362170688091;507.8197239979828;509.28191250336846;510.17097217022337;4617.215073437524;4628.768561571908;4634.443866736014;4642.837500687459;4665.346851440801;11035.558577656964;11907;12301.938490307492;12312.199707019194;12340.568080117153;11640.476178571984;11829.349385783693;12137.635870923887;12209;12247.469066366704;8932.330075244383;9077.727668169833;9077.77305567366;9081.909180908106;9098.909010909807;6612.437942774918;6792.218894827158;6834.1457317835275;6901.068355772108;7012.298770123151;10836.620718274822;11276.774464510829;11324;11535.192536522363;11592.188546801643;11314.208005439541;11482;11673.591424300108;11694.824577631513;11824.23142495735;8569.228769410784;8810.603522841615;8823.250023747796;9140.360174787702;9518.61925522999;8876.822463550821;9172.954135229282;9366.718998430058;9487.857682134636;9697.6605818796;8577;8771.079036701054;8792.120787921156;8830.072842351461;8830.602622882114;8319.916800831914;8457.196566326189;8584.871226931476;8632;8635.222829945084;7794.610269486617;7802.609869506388;7841.059072911059;7848.293653571206;7866.6066696666085;5660.207570940154;5676.233708449472;5776.942230577641;5803.564732644984;5855.677937713466;5624;5651.463111004448;5685.118806584986;5718.027935250934;5768.221290125779 08:44:36 INFO - PROCESS | 6047 | |4;prototype.html;348.66179805588587;354.9432090865461;360.83762306961864;363.36229916496546;364.19694573465495;5531.363893152288;5552.722363881805;5572.916406253909;5574.247476590659;5619.859503512414;5280.287161233242;5374.95751614386;5386.245925570413;5467.890642187154;5586.804461843836;4311.8706438806885;4348.673849461303;4350.564943505656;4371.519132895387;4374.212641724488;4351.325544540601;4370.497392799821;4388.868333949987;4392.736435813846;4402.207602631525;4075.755454672714;4087.6934229932726;4307.461567304087;4344.674149438804;4347.7826108694435;4264.701470897038;4309.892252693677;4313.309870420733;4339.305711086211;4369.3227549729845;3849.6920246380223;3869.5743468218475;3881.107345310594;3897.7856217908;3915;5118.232265160219;5294.841154765363;5310.44240354761;5316.803719163196;5406.107992181285;4926.5812405945535;4972.8508144755715;4979.30289759434;4979.526944940225;5027.597792176617;5712.2288491054005;5723.627964182357;5725;5765.7981970631245;5783.421657834225;4054;4226.915461690749;4229.767362795045;4266.1467706458525;4314.913701725979;5923.526117910514;5944.524438044946;5946.524278057745;5962.612430192024;5988.550858685616;4349.804258808298;4362;4376.708870883097;4388.012697143181;4398.714083584621;4332.393464914914;4397.94449332158;4400.977995110068;4418.513963464016;4424.646028317791;4962.255661650709;4999.47505511916;5001.24981252815;5005.74971251436;5034.748262586856;5788.479036886701;5812.215350927655;5838.927180850822;5851.444112809277;5882.55880808941;1418.5035237666941;1419.340006896798;1489.9702005960037;1494.4844028810044;1507.3443052272296;995.8058178655237;998.3959704378891;1006.1648831469862;1009.6264382178495;1026.707388394319;4957.429895561984;4990.026944745812;5028.178584275976;5042.520960508746;5048.192289233705;5228.503292187236;5268.236105764643;5276.92084618731;5319.335083114611;5319.6593498860375;4843;5005.224190250517;5028.647994640413;5030.220315851049;5037.722925239147;2827;2851.9305260527303;2869.6512639060024;2878.7553057990303;2886.6247387839867;5432.538234250133;5450.700211488406;5520.613557050968;5556.388797232301;5568.359638641527;5166.974165129151;5265.341832270966;5351.892962140735;5370.543503802142;5374.220737992969;5455.509004189642;5472.480114389126;5483.8080667176455;5539.612227144061;5558;5230.607704422107;5259.263703081495;5269.972355390815;5301.602379821452;5389;4930.334404855299;5196.350456192976;5228.869278267922;5235.712035837913;5252.2646829444675;5423.28504974094;5431.750697339555;5441.945580544144;5468.671879687232;5524.58565607573;3970.344893092723;3991.2217117661776;3996.500437445319;4030.2544029354663;4035.9596404035583;4941.703497790144;5274.841754747364;5283.286756287852;5286.497782710637;5679.251693882018;5643.717814109196;5753.309602847685;5781;5838.701133859356;5879.617824841372;5866.208061911588;5877.265341832271;5883.264591926009;5892.440218179266;5899.528037757055;5601.971990140023;5657.773689052392;5732.283464566929;5739.2252045973255;5783.3638299787835;5757.625754325955;5818;5880;5894.793682221101;5922.1412895130825;5556.472135147154;5676.858078548069;5704.686242256716;5724.914126288192;5769.74036168365 08:44:36 INFO - PROCESS | 6047 | |5;yui.html;780.0989856715496;785.8467598818229;788.7160622176016;795.4233180943817;845.3152946113645;14429.278536073194;15331.156786376772;15405.6148596285;15619.531414057568;16478.835211647878;16595.253213605418;17571.91214043931;17573.91213043933;17576.560585985357;17582.648347033053;734.383118180728;893.2050475077186;895.8880139982502;899.928005759541;900.2078171209326;861.6682577207757;882.642529775442;889.3729920406098;895.6283142495856;895.7044175422128;931.6413180925325;932.4591736792644;934.1125930366179;935.4995077633467;936.2042264075516;768.2509553185655;777.8288776469168;920.0431551186758;927.554773708617;927.8237134944372;586.7418335932219;668.4485299627827;675.7533500272415;681.151965802579;690.3234829866708;16443.342266309333;16446.424375146933;16480.17599120051;16523.66952660952;16547.172641367884;14263.429462821474;14610.70778584433;14713.337899794535;14779.926100369537;14786.852131478656;13149.60551183466;13429.39567719455;13573.796393054112;13779.242141682204;14050.43798248069;4086.734362266443;4128.896777580554;4148.8340466381305;4155.252054630166;4268.850590229358;81975.5901220496;82499;82609.9999999994;85022.57488712517;85833.57083214607;11889.404135141827;12074.27554346742;12151.270923744603;12215.389230538438;12363.75272494545;22086;22105.33683989483;22127.668084978737;22128;22164.556708865734;9161.083891610919;9398.483083430478;9454;9473.147416732389;9512.809743805085;5288.286081379041;5297.867553311198;5392.892142157213;5416.431274716098;5517.2551705520045;4042.797860106983;4093.529244136903;4155.127423241153;4162;4166.604172603597;689.3141324382171;693.4209934704477;695.0686080651951;695.537467584061;701.9894701579481;701.9786211062993;758.6320634492267;808.4906508899473;818.4107442641289;819.692615269274;10780.46097695112;10846.728831779112;10900.563977440814;10908.836367454494;11194.776104478027;731.8170457385654;783.5494590610422;786.5241528875063;802.0856223904754;802.334062727946;5126.436092029874;5277.868053298698;5283.973580132151;5304.310439642822;5309.787608495617;119776.40111799385;121761;122824;124500.37749811374;126931.36534317453;99939;105800.47099764606;107965.4601727002;108469;108905.45547272213;92429.53785231165;94732.0526794737;96054;96884;97112.51443742737;10284.627335378289;10320.277580569287;10411.323263987817;10452.634157804441;10619.415932123808;55281;55482.72258638681;56070.43929560815;56278.15582766344;56805;71664.64167679129;77571.6121419412;77639;78691.60654196692;78729.60635197017;13047.478100875858;13049.28228947417;13116.409761560562;13203.867961320264;13449.529266475627;15393.615159621098;15673.216339182767;16568.17159142062;16677.749833752747;17012.404565840137;14718;14784.852151478348;14922.850771492145;15089.24553772285;15699.979501332376;14981.325840336993;15153.469628562947;15168.696626067196;15331.080135191924;15714.13572253537;14516.782248266074;15096.320665570296;15198.544043678707;15489.380424782883;15918;10766.784664306826;10807.243492955418;10865.837012444661;10912.127029837753;11755.059595232531;11199.66401007971;11514.654560363202;11627.083302155483;11791.646250612495;11899.405029748652 08:44:36 INFO - PROCESS | 6047 | __end_tp_report 08:44:36 INFO - PROCESS | 6047 | __start_cc_report 08:44:36 INFO - PROCESS | 6047 | _x_x_mozilla_cycle_collect,918 08:44:36 INFO - PROCESS | 6047 | __end_cc_report 08:44:36 INFO - PROCESS | 6047 | __startTimestamp1466523876916__endTimestamp 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | ------- Summary: start ------- 08:44:36 INFO - PROCESS | 6047 | Number of tests: 6 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | [#0] dojo.html Cycles:165 Average:5660.31 Median:5856.56 stddev:644.81 (11.0%) stddev-sans-first:621.87 08:44:36 INFO - PROCESS | 6047 | Values: 3397.1 3397.9 3403.7 3431.6 3454.6 5678.5 5709.4 5745.3 5762.7 5763.1 5675.1 5736.3 5737.3 5748.0 5748.5 5060.9 5171.0 5218.5 5237.1 5279.9 5068.8 5182.4 5217.3 5219.2 5220.1 5121.4 5145.5 5158.8 5192.6 5202.9 5259.4 5268.8 5269.7 5271.1 5306.4 5016.9 5018.7 5123.0 5155.2 5162.7 5485.3 5541.1 5549.9 5550.1 5601.4 6064.6 6091.2 6092.9 6095.2 6120.2 6085.5 6095.6 6120.7 6146.7 6178.0 5739.8 5760.7 5782.5 5793.2 5799.2 6075.4 6319.9 6328.9 6333.4 6335.5 5918.9 5952.6 5992.9 6041.5 6068.2 6103.6 6113.1 6115.7 6119.1 6142.8 5856.6 5948.3 6063.3 6091.1 6099.8 5990.5 6072.8 6086.2 6086.5 6100.8 4607.3 4616.3 4621.2 4622.2 4623.2 4019.6 4035.6 4072.2 4131.1 4150.5 5705.2 5726.1 5730.2 5737.3 5744.9 5985.0 6049.6 6049.8 6059.6 6078.1 5640.7 5736.0 5778.5 5789.2 5829.4 5129.1 5160.1 5168.4 5182.7 5187.8 6110.6 6390.1 6402.3 6408.7 6421.2 6066.8 6123.6 6124.2 6138.3 6166.9 6353.7 6371.3 6374.8 6375.2 6391.8 5828.5 5998.8 6060.6 6061.4 6062.0 6114.5 6116.2 6157.3 6157.6 6159.7 6001.0 6001.6 6002.2 6007.6 6034.6 5761.0 5886.8 5905.1 5933.3 6011.9 5855.4 5868.6 5872.6 5876.2 5887.4 6031.3 6031.4 6046.4 6078.8 6081.1 5701.9 5709.9 5740.7 5777.0 5955.0 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | [#1] ext.html Cycles:180 Average:51969.85 Median:38104.62 stddev:91580.10 (240.3%) stddev-sans-first:91755.77 08:44:36 INFO - PROCESS | 6047 | Values: 595.2 639.2 654.0 657.7 665.7 37165.6 37935.6 51063.0 52662.5 52663.2 49615.0 50268.5 50395.0 50444.5 50459.7 9194.8 9341.4 9342.6 9351.8 9356.7 8423.2 10865.1 11120.1 11125.1 11128.1 10848.4 10950.7 10956.1 11273.7 11283.9 8382.2 8583.1 11125.3 11203.9 11229.8 10654.5 10986.6 11020.2 11373.4 11395.5 46809.5 47330.5 47357.5 47375.3 47452.8 3871.7 3948.3 4476.3 4505.9 4575.7 35690.0 36285.3 36422.1 36496.3 36639.3 4732.6 4737.2 4780.3 4809.8 4888.8 364569.0 367575.0 367837.2 368341.0 368722.0 36604.8 37436.4 70047.6 71274.6 71437.6 39767.6 39843.8 39859.6 39935.8 39951.2 17943.4 17983.9 17991.5 17999.5 18002.8 13128.6 14142.2 14148.7 14458.1 14543.1 9373.4 9376.1 9380.6 9436.5 9445.4 3460.2 3508.2 3557.0 3559.0 3581.0 1073.7 1076.2 1150.6 1197.8 1200.7 34564.8 35132.8 35278.0 35332.5 35434.8 1157.7 1202.7 1215.1 1218.8 1266.7 17911.2 18306.0 18367.8 18441.8 18549.5 170864.0 170880.0 171335.0 171612.7 171960.0 66429.7 66929.0 67070.0 67103.0 67146.7 1272.4 1392.3 1416.6 1465.6 1490.9 33129.3 34136.3 34168.0 34365.0 35210.8 56839.7 57534.4 57572.1 57678.0 57753.0 435166.0 443650.0 443778.0 443912.0 444265.0 28476.7 30825.5 31537.2 32257.0 33401.5 51971.5 53497.5 53527.0 54167.2 54725.7 44492.0 45660.8 45984.0 45986.0 46971.5 43636.3 44404.8 44659.8 44991.8 46139.8 44123.1 45090.8 45210.3 45451.5 45658.0 38273.6 39403.8 39754.8 40177.2 40510.2 29278.0 29614.2 29956.4 30163.4 30181.5 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | [#2] jquery.html Cycles:180 Average:29509.26 Median:20600.88 stddev:80709.93 (391.8%) stddev-sans-first:80906.79 08:44:36 INFO - PROCESS | 6047 | Values: 424.9 427.4 429.7 429.8 441.7 18708.5 19468.5 20586.0 20615.8 20634.6 19056.0 19656.1 19739.1 19896.6 19919.9 5729.7 5958.3 5983.6 5988.0 5991.2 5718.3 5867.7 5876.2 5908.6 6024.2 6074.5 6098.5 6121.6 6121.9 6124.0 5949.8 5979.6 6094.1 6101.1 6103.0 5809.3 5826.8 5862.4 5870.9 5881.3 14140.4 14674.1 14865.9 14904.6 14919.6 2432.4 2459.8 2582.1 2809.7 2861.7 10307.7 11054.2 11168.6 11216.0 11860.4 1896.3 2008.9 2046.2 2067.4 2109.9 85958.6 88702.0 89925.1 90624.0 90695.0 11691.7 11709.6 11873.3 11991.5 12035.3 16739.9 16761.3 16811.0 16874.2 16890.2 8282.8 8325.0 8498.2 8596.6 8822.6 7902.0 8166.0 8575.7 8715.5 8817.3 5030.7 5047.6 5077.5 5096.5 5100.9 2016.4 2030.2 2055.9 2057.5 2074.4 922.5 1016.9 1030.9 1052.9 1068.2 19402.9 19977.5 19989.2 20021.2 20299.6 989.7 998.4 1021.6 1035.7 1041.4 10741.1 11160.2 11163.1 11271.7 11328.7 473932.0 479086.0 486226.0 491019.0 504493.0 100861.0 101507.0 101844.0 102335.5 102622.0 1033.6 1035.8 1036.4 1050.2 1054.1 17632.8 17765.9 17927.9 17950.4 18328.5 30978.7 40737.8 41720.0 42768.8 42785.1 60275.7 60671.4 61224.7 61516.4 61837.4 8584.1 8735.0 8760.4 8943.3 9057.5 12286.8 13143.4 13503.6 13571.4 13620.6 9319.3 11652.9 11926.8 12247.6 12250.9 11928.2 12006.2 12031.7 12068.6 12358.3 12120.9 12189.3 12239.2 12295.2 12303.2 7945.6 8215.2 8607.2 8941.0 9322.5 11204.4 11806.4 11812.3 11887.7 12532.9 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | [#3] mootools.html Cycles:180 Average:6427.45 Median:504.16 stddev:3700.91 (734.1%) stddev-sans-first:3692.13 08:44:36 INFO - PROCESS | 6047 | Values: 1415.9 1520.8 1561.8 1746.3 1874.4 8984.2 9321.2 9330.1 9332.8 9616.8 8980.7 9230.0 9534.7 9538.6 9547.9 2135.2 2186.1 2487.8 2523.7 2577.8 2586.6 2622.8 2623.2 2624.9 2629.6 2525.6 2564.5 2583.5 2583.6 2586.0 2132.9 2430.3 2455.7 2502.5 2511.5 2506.0 2512.7 2518.5 2533.2 2539.9 9486.0 9547.5 9549.5 9552.8 9595.3 5659.5 5866.3 5875.9 5876.9 5877.8 3662.6 3664.9 3791.5 3865.8 3880.4 1269.8 1285.5 1291.0 1314.2 1336.6 13523.3 13859.4 13869.7 13896.4 13896.9 5111.6 5118.6 5140.4 5214.8 5232.3 11634.7 11648.9 11661.5 11662.9 11667.2 5785.5 5785.7 5786.3 5786.3 5787.8 4719.2 5242.9 5336.6 5349.0 5444.8 4440.2 4443.3 4477.3 4487.9 4505.4 1371.6 1418.5 1418.7 1422.0 1427.6 994.3 1064.8 1074.6 1088.4 1106.6 6630.2 6731.5 6794.3 6817.2 6867.5 503.2 505.1 507.8 509.3 510.2 4617.2 4628.8 4634.4 4642.8 4665.3 11035.6 11907.0 12301.9 12312.2 12340.6 11640.5 11829.3 12137.6 12209.0 12247.5 8932.3 9077.7 9077.8 9081.9 9098.9 6612.4 6792.2 6834.1 6901.1 7012.3 10836.6 11276.8 11324.0 11535.2 11592.2 11314.2 11482.0 11673.6 11694.8 11824.2 8569.2 8810.6 8823.3 9140.4 9518.6 8876.8 9173.0 9366.7 9487.9 9697.7 8577.0 8771.1 8792.1 8830.1 8830.6 8319.9 8457.2 8584.9 8632.0 8635.2 7794.6 7802.6 7841.1 7848.3 7866.6 5660.2 5676.2 5776.9 5803.6 5855.7 5624.0 5651.5 5685.1 5718.0 5768.2 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | [#4] prototype.html Cycles:180 Average:4704.34 Median:5233.16 stddev:1336.41 (25.5%) stddev-sans-first:1299.56 08:44:36 INFO - PROCESS | 6047 | Values: 348.7 354.9 360.8 363.4 364.2 5531.4 5552.7 5572.9 5574.2 5619.9 5280.3 5375.0 5386.2 5467.9 5586.8 4311.9 4348.7 4350.6 4371.5 4374.2 4351.3 4370.5 4388.9 4392.7 4402.2 4075.8 4087.7 4307.5 4344.7 4347.8 4264.7 4309.9 4313.3 4339.3 4369.3 3849.7 3869.6 3881.1 3897.8 3915.0 5118.2 5294.8 5310.4 5316.8 5406.1 4926.6 4972.9 4979.3 4979.5 5027.6 5712.2 5723.6 5725.0 5765.8 5783.4 4054.0 4226.9 4229.8 4266.1 4314.9 5923.5 5944.5 5946.5 5962.6 5988.6 4349.8 4362.0 4376.7 4388.0 4398.7 4332.4 4397.9 4401.0 4418.5 4424.6 4962.3 4999.5 5001.2 5005.7 5034.7 5788.5 5812.2 5838.9 5851.4 5882.6 1418.5 1419.3 1490.0 1494.5 1507.3 995.8 998.4 1006.2 1009.6 1026.7 4957.4 4990.0 5028.2 5042.5 5048.2 5228.5 5268.2 5276.9 5319.3 5319.7 4843.0 5005.2 5028.6 5030.2 5037.7 2827.0 2851.9 2869.7 2878.8 2886.6 5432.5 5450.7 5520.6 5556.4 5568.4 5167.0 5265.3 5351.9 5370.5 5374.2 5455.5 5472.5 5483.8 5539.6 5558.0 5230.6 5259.3 5270.0 5301.6 5389.0 4930.3 5196.4 5228.9 5235.7 5252.3 5423.3 5431.8 5441.9 5468.7 5524.6 3970.3 3991.2 3996.5 4030.3 4036.0 4941.7 5274.8 5283.3 5286.5 5679.3 5643.7 5753.3 5781.0 5838.7 5879.6 5866.2 5877.3 5883.3 5892.4 5899.5 5602.0 5657.8 5732.3 5739.2 5783.4 5757.6 5818.0 5880.0 5894.8 5922.1 5556.5 5676.9 5704.7 5724.9 5769.7 08:44:36 INFO - PROCESS | 6047 | 08:44:36 INFO - PROCESS | 6047 | [#5] yui.html Cycles:180 Average:22433.73 Median:4090.13 stddev:32212.97 (787.6%) stddev-sans-first:32262.30 08:44:36 INFO - PROCESS | 6047 | Values: 780.1 785.8 788.7 795.4 845.3 14429.3 15331.2 15405.6 15619.5 16478.8 16595.3 17571.9 17573.9 17576.6 17582.6 734.4 893.2 895.9 899.9 900.2 861.7 882.6 889.4 895.6 895.7 931.6 932.5 934.1 935.5 936.2 768.3 777.8 920.0 927.6 927.8 586.7 668.4 675.8 681.2 690.3 16443.3 16446.4 16480.2 16523.7 16547.2 14263.4 14610.7 14713.3 14779.9 14786.9 13149.6 13429.4 13573.8 13779.2 14050.4 4086.7 4128.9 4148.8 4155.3 4268.9 81975.6 82499.0 82610.0 85022.6 85833.6 11889.4 12074.3 12151.3 12215.4 12363.8 22086.0 22105.3 22127.7 22128.0 22164.6 9161.1 9398.5 9454.0 9473.1 9512.8 5288.3 5297.9 5392.9 5416.4 5517.3 4042.8 4093.5 4155.1 4162.0 4166.6 689.3 693.4 695.1 695.5 702.0 702.0 758.6 808.5 818.4 819.7 10780.5 10846.7 10900.6 10908.8 11194.8 731.8 783.5 786.5 802.1 802.3 5126.4 5277.9 5284.0 5304.3 5309.8 119776.4 121761.0 122824.0 124500.4 126931.4 99939.0 105800.5 107965.5 108469.0 108905.5 92429.5 94732.1 96054.0 96884.0 97112.5 10284.6 10320.3 10411.3 10452.6 10619.4 55281.0 55482.7 56070.4 56278.2 56805.0 71664.6 77571.6 77639.0 78691.6 78729.6 13047.5 13049.3 13116.4 13203.9 13449.5 15393.6 15673.2 16568.2 16677.7 17012.4 14718.0 14784.9 14922.9 15089.2 15700.0 14981.3 15153.5 15168.7 15331.1 15714.1 14516.8 15096.3 15198.5 15489.4 15918.0 10766.8 10807.2 10865.8 10912.1 11755.1 11199.7 11514.7 11627.1 11791.6 11899.4 08:44:36 INFO - PROCESS | 6047 | -------- Summary: end -------- 08:44:36 INFO - PROCESS | 6047 | 08:44:37 INFO - TEST-INFO | 6047: exit 0 08:44:37 INFO - TEST-OK | dromaeo_css | took 1113271ms 08:44:37 INFO - TEST-START | kraken 08:44:37 INFO - operating with platform_type : linux_ 08:44:37 INFO - Initialising browser for kraken test... 08:44:37 INFO - TEST-INFO | started process 6234 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiISt1O/profile http://localhost:59192/getInfo.html) 08:44:41 INFO - PROCESS | 6234 | __metrics Screen width/height:1600/1200 08:44:41 INFO - PROCESS | 6234 | colorDepth:24 08:44:41 INFO - PROCESS | 6234 | Browser inner width/height: 1024/697 08:44:41 INFO - PROCESS | 6234 | __metrics 08:44:42 ERROR - PROCESS | 6234 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:44:42 INFO - PROCESS | 6234 | console.error: PushService: 08:44:42 INFO - PROCESS | 6234 | stateChangeProcessEnqueue: Error transitioning state 08:44:42 INFO - PROCESS | 6234 | UnknownError 08:44:43 INFO - TEST-INFO | 6234: exit 0 08:44:43 INFO - Browser initialized. 08:44:43 INFO - Running cycle 1/1 for kraken test... 08:44:43 INFO - TEST-INFO | started process 6328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiISt1O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:55 INFO - PROCESS | 6328 | RSS: Main: 145514496 08:44:55 INFO - PROCESS | 6328 | 08:44:58 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59192/tests/kraken/driver.html?testName=audio-beat-detection) 08:44:58 INFO - PROCESS | 6328 | RSS: Main: 194945024 08:44:58 INFO - PROCESS | 6328 | 08:45:01 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59192/tests/kraken/driver.html?testName=audio-dft) 08:45:02 INFO - PROCESS | 6328 | RSS: Main: 192225280 08:45:02 INFO - PROCESS | 6328 | 08:45:04 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59192/tests/kraken/driver.html?testName=audio-fft) 08:45:05 INFO - PROCESS | 6328 | RSS: Main: 279306240 08:45:05 INFO - PROCESS | 6328 | 08:45:07 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59192/tests/kraken/driver.html?testName=audio-oscillator) 08:45:08 INFO - PROCESS | 6328 | RSS: Main: 187760640 08:45:08 INFO - PROCESS | 6328 | 08:45:10 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59192/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:45:11 INFO - PROCESS | 6328 | RSS: Main: 188325888 08:45:11 INFO - PROCESS | 6328 | 08:45:14 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59192/tests/kraken/driver.html?testName=imaging-darkroom) 08:45:14 INFO - PROCESS | 6328 | RSS: Main: 223379456 08:45:14 INFO - PROCESS | 6328 | 08:45:17 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59192/tests/kraken/driver.html?testName=imaging-desaturate) 08:45:18 INFO - PROCESS | 6328 | RSS: Main: 222834688 08:45:18 INFO - PROCESS | 6328 | 08:45:21 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59192/tests/kraken/driver.html?testName=json-parse-financial) 08:45:21 INFO - PROCESS | 6328 | RSS: Main: 222613504 08:45:21 INFO - PROCESS | 6328 | 08:45:23 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59192/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:45:24 INFO - PROCESS | 6328 | RSS: Main: 200777728 08:45:24 INFO - PROCESS | 6328 | 08:45:25 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:45:26 INFO - PROCESS | 6328 | RSS: Main: 200982528 08:45:26 INFO - PROCESS | 6328 | 08:45:28 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:45:29 INFO - PROCESS | 6328 | RSS: Main: 209932288 08:45:29 INFO - PROCESS | 6328 | 08:45:31 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:45:32 INFO - PROCESS | 6328 | RSS: Main: 216453120 08:45:32 INFO - PROCESS | 6328 | 08:45:34 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:45:35 INFO - PROCESS | 6328 | RSS: Main: 215719936 08:45:35 INFO - PROCESS | 6328 | 08:45:36 INFO - PROCESS | 6328 | Cycle 1(1): loaded http://localhost:59192/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59192/tests/kraken/driver.html?testName=ai-astar) 08:45:37 INFO - PROCESS | 6328 | RSS: Main: 257982464 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | __start_tp_report 08:45:37 INFO - PROCESS | 6328 | _x_x_mozilla_page_load 08:45:37 INFO - PROCESS | 6328 | _x_x_mozilla_page_load_details 08:45:37 INFO - PROCESS | 6328 | |i|pagename|runs| 08:45:37 INFO - PROCESS | 6328 | |0;ai-astar;111;102;110;111;110;109;111;109;110;111 08:45:37 INFO - PROCESS | 6328 | |1;audio-beat-detection;154;124;128;172;124;126;152;131;166;122 08:45:37 INFO - PROCESS | 6328 | |2;audio-dft;162;161;162;162;162;163;161;161;162;163 08:45:37 INFO - PROCESS | 6328 | |3;audio-fft;75;74;76;107;75;75;76;76;101;74 08:45:37 INFO - PROCESS | 6328 | |4;audio-oscillator;84;84;109;83;83;84;118;84;85;122 08:45:37 INFO - PROCESS | 6328 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;117;117;118 08:45:37 INFO - PROCESS | 6328 | |6;imaging-darkroom;146;146;145;146;147;146;146;146;146;145 08:45:37 INFO - PROCESS | 6328 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 08:45:37 INFO - PROCESS | 6328 | |8;json-parse-financial;52;50;50;51;51;51;54;51;53;51 08:45:37 INFO - PROCESS | 6328 | |9;json-stringify-tinderbox;59;60;60;59;60;60;60;60;60;60 08:45:37 INFO - PROCESS | 6328 | |10;stanford-crypto-aes;76;76;79;76;77;77;78;78;118;77 08:45:37 INFO - PROCESS | 6328 | |11;stanford-crypto-ccm;119;119;122;118;120;115;119;118;120;120 08:45:37 INFO - PROCESS | 6328 | |12;stanford-crypto-pbkdf2;159;155;160;158;153;154;156;156;152;156 08:45:37 INFO - PROCESS | 6328 | |13;stanford-crypto-sha256-iterative;62;61;64;61;61;61;60;62;71;60 08:45:37 INFO - PROCESS | 6328 | __end_tp_report 08:45:37 INFO - PROCESS | 6328 | __start_cc_report 08:45:37 INFO - PROCESS | 6328 | _x_x_mozilla_cycle_collect,1592 08:45:37 INFO - PROCESS | 6328 | __end_cc_report 08:45:37 INFO - PROCESS | 6328 | __startTimestamp1466523937211__endTimestamp 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | ------- Summary: start ------- 08:45:37 INFO - PROCESS | 6328 | Number of tests: 14 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:2.72 (2.5%) stddev-sans-first:2.82 08:45:37 INFO - PROCESS | 6328 | Values: 111.0 102.0 110.0 111.0 110.0 109.0 111.0 109.0 110.0 111.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:141.50 stddev:19.14 (13.5%) stddev-sans-first:19.61 08:45:37 INFO - PROCESS | 6328 | Values: 154.0 124.0 128.0 172.0 124.0 126.0 152.0 131.0 166.0 122.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 08:45:37 INFO - PROCESS | 6328 | Values: 162.0 161.0 162.0 162.0 162.0 163.0 161.0 161.0 162.0 163.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:12.28 (16.4%) stddev-sans-first:12.84 08:45:37 INFO - PROCESS | 6328 | Values: 75.0 74.0 76.0 107.0 75.0 75.0 76.0 76.0 101.0 74.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:84.00 stddev:16.01 (19.1%) stddev-sans-first:16.60 08:45:37 INFO - PROCESS | 6328 | Values: 84.0 84.0 109.0 83.0 83.0 84.0 118.0 84.0 85.0 122.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 08:45:37 INFO - PROCESS | 6328 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 118.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 08:45:37 INFO - PROCESS | 6328 | Values: 146.0 146.0 145.0 146.0 147.0 146.0 146.0 146.0 146.0 145.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:45:37 INFO - PROCESS | 6328 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.00 stddev:1.26 (2.5%) stddev-sans-first:1.32 08:45:37 INFO - PROCESS | 6328 | Values: 52.0 50.0 50.0 51.0 51.0 51.0 54.0 51.0 53.0 51.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 08:45:37 INFO - PROCESS | 6328 | Values: 59.0 60.0 60.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:77.00 stddev:12.97 (16.8%) stddev-sans-first:13.62 08:45:37 INFO - PROCESS | 6328 | Values: 76.0 76.0 79.0 76.0 77.0 77.0 78.0 78.0 118.0 77.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#11] stanford-crypto-ccm Cycles:10 Average:119.00 Median:119.50 stddev:1.83 (1.5%) stddev-sans-first:1.94 08:45:37 INFO - PROCESS | 6328 | Values: 119.0 119.0 122.0 118.0 120.0 115.0 119.0 118.0 120.0 120.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.90 Median:156.00 stddev:2.56 (1.6%) stddev-sans-first:2.46 08:45:37 INFO - PROCESS | 6328 | Values: 159.0 155.0 160.0 158.0 153.0 154.0 156.0 156.0 152.0 156.0 08:45:37 INFO - PROCESS | 6328 | 08:45:37 INFO - PROCESS | 6328 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:61.50 stddev:3.27 (5.3%) stddev-sans-first:3.46 08:45:37 INFO - PROCESS | 6328 | Values: 62.0 61.0 64.0 61.0 61.0 61.0 60.0 62.0 71.0 60.0 08:45:37 INFO - PROCESS | 6328 | -------- Summary: end -------- 08:45:37 INFO - PROCESS | 6328 | 08:45:38 INFO - TEST-INFO | 6328: exit 0 08:45:38 INFO - TEST-OK | kraken | took 60562ms 08:45:38 INFO - SUITE-END | took 1173s 08:45:38 INFO - Completed test suite (00:19:34) 08:45:38 INFO - javascript benchmark 08:45:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3397.065806903102, 3397.9150521236975, 3403.7447191460633, 3431.5882094148706, 3454.55090838191, 5678.48893599576, 5709.371969083398, 5745.253117094782, 5762.654240745553, 5763.0779075347955, 5675.1203563447625, 5736.282964629421, 5737.282839645044, 5748.02283611786, 5748.5401167906575, 5060.898782024357, 5171.0, 5218.530332270095, 5237.083510385686, 5279.894402111955, 5068.8225912093085, 5182.404023537304, 5217.295665085222, 5219.217117432378, 5220.086484865153, 5121.385433747955, 5145.459726728695, 5158.845234642967, 5192.558632516222, 5202.947970520284, 5259.368875734898, 5268.762905669216, 5269.65747226431, 5271.130263506517, 5306.416294207634, 5016.924746128811, 5018.7490625468945, 5122.974385128051, 5155.200943853709, 5162.741862906877, 5485.314335708036, 5541.141123125922, 5549.861253468695, 5550.0564903966015, 5601.383847776782, 6064.575479716422, 6091.228630771719, 6092.878142437126, 6095.177469327658, 6120.234970628671, 6085.4523092921845, 6095.605389230519, 6120.721420533214, 6146.7233974471255, 6177.980633195517, 5739.827805165852, 5760.711964401763, 5782.537397008229, 5793.188953546507, 5799.246098007275, 6075.422834830684, 6319.905201421982, 6328.936710632835, 6333.366663333722, 6335.524835637346, 5918.911216331759, 5952.583319167703, 5992.910106348409, 6041.486473649701, 6068.21113255274, 6103.6032657877095, 6113.083037544404, 6115.662097952266, 6119.082137679384, 6142.78500252489, 5856.560757943172, 5948.315943666534, 6063.302720187235, 6091.116788065706, 6099.786507472217, 5990.460858522754, 6072.7570897164505, 6086.239220097488, 6086.513078953673, 6100.755969761249, 4607.262837945913, 4616.307553866947, 4621.19129152397, 4622.214223581932, 4623.190941585276, 4019.618136277049, 4035.6367926886132, 4072.165206132736, 4131.132462182975, 4150.460440142762, 5705.229793977843, 5726.055200891889, 5730.19777231188, 5737.311522617312, 5744.942550574441, 5985.0, 6049.606775559574, 6049.8487537811025, 6059.606125601822, 6078.057901025348, 5640.717964101778, 5736.024875771045, 5778.479936805624, 5789.189513468118, 5829.387914269026, 5129.102407078746, 5160.071187186342, 5168.379794424618, 5182.740862956837, 5187.818426355059, 6110.5722599418505, 6390.137331460286, 6402.295747467775, 6408.743650254031, 6421.197350331208, 6066.817995460143, 6123.571350005457, 6124.216183935432, 6138.324784273816, 6166.938330616636, 6353.745850165941, 6371.331010243857, 6374.808755737336, 6375.234971803413, 6391.840203994937, 5828.475437210672, 5998.760049597967, 6060.636361818305, 6061.393860613904, 6061.969690151521, 6114.5414093942245, 6116.214037636738, 6157.291911430243, 6157.568970172045, 6159.6612186330185, 6001.039833626599, 6001.609895356788, 6002.249718785152, 6007.639541627516, 6034.607750496204, 5761.049426844524, 5886.823395298148, 5905.143754155709, 5933.2880054393745, 6011.939880601138, 5855.443732845373, 5868.559858010581, 5872.588918775645, 5876.236089308362, 5887.381824908323, 6031.336552979256, 6031.36670649593, 6046.382115438178, 6078.848028799315, 6081.057436097412, 5701.885962280814, 5709.914351284651, 5740.655560666373, 5777.017906955912, 5955.0], "value": 5617.402155268273, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.1697382151898, 639.2201514152733, 653.9673016349182, 657.707320242492, 665.7436886798587, 37165.628343716555, 37935.620643793554, 51062.999999999956, 52662.473375266236, 52663.21005184925, 49615.0, 50268.49731502684, 50395.0, 50444.495555044436, 50459.747701261535, 9194.770130746718, 9341.43951362917, 9342.57958391874, 9351.76620584484, 9356.672516461926, 8423.15768423159, 10865.130789536857, 11120.110391168688, 11125.054370378528, 11128.109751219883, 10848.403337816417, 10950.726231844228, 10956.068734157607, 11273.661790146309, 11283.943580282086, 8382.20369064944, 8583.05586385497, 11125.332480051224, 11203.943980280128, 11229.775404491946, 10654.520546575422, 10986.615468458565, 11020.173486988508, 11373.431328433546, 11395.54417823286, 46809.53190468086, 47330.52669473296, 47357.52642473565, 47375.28937065947, 47452.76273618644, 3871.728978971473, 3948.309045916953, 4476.306172543228, 4505.909881802379, 4575.702579332333, 35690.0, 36285.27429451422, 36422.089447763756, 36496.27007459836, 36639.267214655825, 4732.574068333832, 4737.1946769049, 4780.306855505932, 4809.831655892062, 4888.828890988798, 364569.0, 367575.0, 367837.1608141969, 368341.0, 368722.0, 36604.81697591495, 37436.43845342322, 70047.64976175087, 71274.64362678153, 71437.64281178561, 39767.60232397639, 39843.80078099649, 39859.60140398617, 39935.80032099821, 39951.20097598032, 17943.371981980566, 17983.910080449514, 17991.460256192335, 17999.460016199537, 18002.819971800116, 13128.60614181576, 14142.22217778032, 14148.717025659429, 14458.132512049311, 14543.127412355292, 9373.437593744397, 9376.109269619375, 9380.624775009059, 9436.528173591292, 9445.433274003582, 3460.2387474755515, 3508.19311558343, 3556.9725256604006, 3558.9644103558635, 3580.964190358063, 1073.6617965340893, 1076.1821016027875, 1150.5627861412609, 1197.7663007102524, 1200.7478429529897, 34564.82717586446, 35132.82433587867, 35278.0, 35332.47001294982, 35434.822825885705, 1157.7337212441187, 1202.729385888168, 1215.064400411678, 1218.7684339975553, 1266.65167079054, 17911.193996270198, 18306.0, 18367.81632183688, 18441.81558184401, 18549.536261593297, 170864.0, 170880.0, 171335.0, 171612.70968225895, 171960.0, 66429.66785166043, 66929.0, 67070.0, 67103.0, 67146.66426667836, 1272.4464857786893, 1392.303848075962, 1416.5679467762432, 1465.5530063330573, 1490.8881833862288, 33129.337413252084, 34136.31727365489, 34168.0, 34365.0, 35210.8239458801, 56839.71580142073, 57534.424655752904, 57572.13641795293, 57678.0, 57753.0, 435166.0, 443650.0, 443778.0, 443912.0, 444265.0, 28476.715232848233, 30825.537616935315, 31537.21156971094, 32257.0, 33401.49897751584, 51971.48028519817, 53497.46502534925, 53527.0, 54167.18389235054, 54725.72637136789, 44492.0, 45660.77169614131, 45984.0, 45986.0, 46971.53028469672, 43636.345454817565, 44404.777976109915, 44659.77670111629, 44991.77504112459, 46139.76930115328, 44123.11753764971, 45090.77454612706, 45210.321845173006, 45451.545484546055, 45658.0, 38273.61726382701, 39403.80298098491, 39754.80122599369, 40177.1964560713, 40510.18979620332, 29278.0, 29614.15642631182, 29956.400871982874, 30163.396732064797, 30181.547276790425], "value": 20230.285257294378, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.929886568716, 427.42297897837915, 429.74644959473903, 429.78081178598904, 441.6643351053199, 18708.53228669284, 19468.51328716781, 20585.970701464925, 20615.793842061576, 20634.587308253827, 19056.0, 19656.11547480363, 19739.1117399717, 19896.602067958633, 19919.900400498012, 5729.6562206267545, 5958.314793798725, 5983.58114931955, 5988.041913293874, 5991.191189189469, 5718.34239062509, 5867.677277749722, 5876.2067120938555, 5908.61593996391, 6024.1566180734735, 6074.514038876901, 6098.4511393974535, 6121.602095863777, 6121.908171377433, 6124.0, 5949.762009519614, 5979.581429299951, 6094.146819445281, 6101.054336577838, 6102.993006154002, 5809.302883653946, 5826.796062137847, 5862.355140934494, 5870.911936320958, 5881.323647780518, 14140.434382624682, 14674.119552826865, 14865.851341486556, 14904.62738431537, 14919.552413427615, 2432.440538676097, 2459.754024597544, 2582.1349847801007, 2809.6909339972585, 2861.670907845604, 10307.690769276935, 11054.170937179744, 11168.553257869757, 11216.0, 11860.406979650983, 1896.2699360746074, 2008.9095990680307, 2046.1508473983279, 2067.379786064175, 2109.8523103382618, 85958.57020714856, 88702.0, 89925.10074899232, 90624.0, 90694.99999999935, 11691.707707307385, 11709.590164344207, 11873.346965916957, 11991.520339186334, 12035.338056406807, 16739.91630041842, 16761.32954681799, 16811.0, 16874.15629218534, 16890.239939202766, 8282.8343433131, 8325.0, 8498.150184981452, 8596.613152408157, 8822.647094116292, 7901.960490197627, 8165.95917020423, 8575.656973720981, 8715.520646364384, 8817.29461643067, 5030.673006254582, 5047.596192304607, 5077.543021128043, 5096.464871188472, 5100.897982040338, 2016.354766474714, 2030.1777779999125, 2055.8869262190724, 2057.4753437873546, 2074.3984244569206, 922.5156792683895, 1016.9491525423699, 1030.9381437113798, 1052.8736551613856, 1068.188176985481, 19402.902985484983, 19977.500562486053, 19989.20043198285, 20021.199152033754, 20299.594008119755, 989.6684610655511, 998.4109375468508, 1021.5607288865859, 1035.746242170673, 1041.3907863899703, 10741.140708743438, 11160.16298777595, 11163.106951444028, 11271.718207044725, 11328.660140195807, 473932.0, 479086.0, 486226.0, 491019.0, 504493.0, 100861.0, 101507.0, 101844.0, 102335.48832255791, 102622.0, 1033.565902321027, 1035.7514196592763, 1036.409246729357, 1050.2385770316612, 1054.1408751867205, 17632.82367176312, 17765.911170444582, 17927.910360448113, 17950.371736989142, 18328.450146495627, 30978.69021309758, 40737.79631101825, 41720.0, 42768.78615606902, 42785.14429711451, 60275.69862150837, 60671.39328606657, 61224.693876530335, 61516.38483615107, 61837.381626184964, 8584.055753867195, 8734.956325218587, 8760.386772925835, 8943.284537237136, 9057.501837399006, 12286.754264914473, 13143.408546615234, 13503.59489215325, 13571.389287482285, 13620.591382258548, 9319.30105242096, 11652.94173529127, 11926.821097683714, 12247.571335003233, 12250.877491224974, 11928.224665396721, 12006.21959572625, 12031.699207519532, 12068.637940861789, 12358.258504489759, 12120.878791212328, 12189.329586872807, 12239.20445171061, 12295.200811947196, 12303.200291980993, 7945.6027198640995, 8215.233948861553, 8607.18231767981, 8941.0, 9322.533873306173, 11204.43977801123, 11806.35065071395, 11812.291262524275, 11887.702807429883, 12532.93733531363], "value": 9494.219123253055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.9292035398228, 1520.779486974389, 1561.7891584636075, 1746.257840417822, 1874.4376686993899, 8984.19142277195, 9321.161095501404, 9330.066993300676, 9332.813343733122, 9616.759581010478, 8980.730578082652, 9229.953850230739, 9534.666286679967, 9538.570764315622, 9547.904520954788, 2135.1566131378095, 2186.1146235774536, 2487.825852190347, 2523.6719226500577, 2577.7680008799202, 2586.5602847515875, 2622.7901767858525, 2623.2392606144194, 2624.9212523624324, 2629.5792673172296, 2525.5832787590025, 2564.525562770884, 2583.4962182374443, 2583.560794664902, 2585.987070064647, 2132.9040193191267, 2430.2952143878256, 2455.7421470745494, 2502.5370306493355, 2511.535365957304, 2506.035176457059, 2512.673352464168, 2518.5214809186377, 2533.227365653475, 2539.911103111382, 9486.003969583164, 9547.474888881108, 9549.522523873848, 9552.808943821085, 9595.28035397348, 5659.547236221134, 5866.325372582122, 5875.8531036724, 5876.9118463223085, 5877.823665290048, 3662.5788034376124, 3664.8900532984053, 3791.4502397152532, 3865.768053916774, 3880.3985382265796, 1269.7841366967639, 1285.5372066056211, 1290.986575538198, 1314.2180402660401, 1336.565616174747, 13523.32383380827, 13859.37632806516, 13869.653258668513, 13896.374663140083, 13896.861031389657, 5111.5655169310285, 5118.564921981673, 5140.40885298188, 5214.765335559909, 5232.319798426181, 11634.70913227176, 11648.94175529117, 11661.475233614508, 11662.94168529169, 11667.183297169116, 5785.537157027427, 5785.71071446426, 5786.276715410574, 5786.334571524244, 5787.826365209051, 4719.221328480762, 5242.868928276747, 5336.599755018389, 5349.0, 5444.8094316698725, 4440.2007638625355, 4443.266860967904, 4477.28363461851, 4487.910241795211, 4505.36924830524, 1371.6228037289825, 1418.4680744720729, 1418.7375335562954, 1421.985780142206, 1427.5503216486982, 994.2692121290845, 1064.776396956648, 1074.6185104287865, 1088.357868857362, 1106.5684383090602, 6630.204375474974, 6731.461483081342, 6794.252632210453, 6817.181938167352, 6867.450603951672, 503.18735242582966, 505.1362170688091, 507.8197239979828, 509.28191250336846, 510.17097217022337, 4617.215073437524, 4628.768561571908, 4634.443866736014, 4642.837500687459, 4665.346851440801, 11035.558577656964, 11907.0, 12301.938490307492, 12312.199707019194, 12340.568080117153, 11640.476178571984, 11829.349385783693, 12137.635870923887, 12209.0, 12247.469066366704, 8932.330075244383, 9077.727668169833, 9077.77305567366, 9081.909180908106, 9098.909010909807, 6612.437942774918, 6792.218894827158, 6834.1457317835275, 6901.068355772108, 7012.298770123151, 10836.620718274822, 11276.774464510829, 11324.0, 11535.192536522363, 11592.188546801643, 11314.208005439541, 11482.0, 11673.591424300108, 11694.824577631513, 11824.23142495735, 8569.228769410784, 8810.603522841615, 8823.250023747796, 9140.360174787702, 9518.61925522999, 8876.822463550821, 9172.954135229282, 9366.718998430058, 9487.857682134636, 9697.6605818796, 8577.0, 8771.079036701054, 8792.120787921156, 8830.072842351461, 8830.602622882114, 8319.916800831914, 8457.196566326189, 8584.871226931476, 8632.0, 8635.222829945084, 7794.610269486617, 7802.609869506388, 7841.059072911059, 7848.293653571206, 7866.6066696666085, 5660.207570940154, 5676.233708449472, 5776.942230577641, 5803.564732644984, 5855.677937713466, 5624.0, 5651.463111004448, 5685.118806584986, 5718.027935250934, 5768.221290125779], "value": 5036.812568964532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.66179805588587, 354.9432090865461, 360.83762306961864, 363.36229916496546, 364.19694573465495, 5531.363893152288, 5552.722363881805, 5572.916406253909, 5574.247476590659, 5619.859503512414, 5280.287161233242, 5374.95751614386, 5386.245925570413, 5467.890642187154, 5586.804461843836, 4311.8706438806885, 4348.673849461303, 4350.564943505656, 4371.519132895387, 4374.212641724488, 4351.325544540601, 4370.497392799821, 4388.868333949987, 4392.736435813846, 4402.207602631525, 4075.755454672714, 4087.6934229932726, 4307.461567304087, 4344.674149438804, 4347.7826108694435, 4264.701470897038, 4309.892252693677, 4313.309870420733, 4339.305711086211, 4369.3227549729845, 3849.6920246380223, 3869.5743468218475, 3881.107345310594, 3897.7856217908, 3915.0, 5118.232265160219, 5294.841154765363, 5310.44240354761, 5316.803719163196, 5406.107992181285, 4926.5812405945535, 4972.8508144755715, 4979.30289759434, 4979.526944940225, 5027.597792176617, 5712.2288491054005, 5723.627964182357, 5725.0, 5765.7981970631245, 5783.421657834225, 4054.0, 4226.915461690749, 4229.767362795045, 4266.1467706458525, 4314.913701725979, 5923.526117910514, 5944.524438044946, 5946.524278057745, 5962.612430192024, 5988.550858685616, 4349.804258808298, 4362.0, 4376.708870883097, 4388.012697143181, 4398.714083584621, 4332.393464914914, 4397.94449332158, 4400.977995110068, 4418.513963464016, 4424.646028317791, 4962.255661650709, 4999.47505511916, 5001.24981252815, 5005.74971251436, 5034.748262586856, 5788.479036886701, 5812.215350927655, 5838.927180850822, 5851.444112809277, 5882.55880808941, 1418.5035237666941, 1419.340006896798, 1489.9702005960037, 1494.4844028810044, 1507.3443052272296, 995.8058178655237, 998.3959704378891, 1006.1648831469862, 1009.6264382178495, 1026.707388394319, 4957.429895561984, 4990.026944745812, 5028.178584275976, 5042.520960508746, 5048.192289233705, 5228.503292187236, 5268.236105764643, 5276.92084618731, 5319.335083114611, 5319.6593498860375, 4843.0, 5005.224190250517, 5028.647994640413, 5030.220315851049, 5037.722925239147, 2827.0, 2851.9305260527303, 2869.6512639060024, 2878.7553057990303, 2886.6247387839867, 5432.538234250133, 5450.700211488406, 5520.613557050968, 5556.388797232301, 5568.359638641527, 5166.974165129151, 5265.341832270966, 5351.892962140735, 5370.543503802142, 5374.220737992969, 5455.509004189642, 5472.480114389126, 5483.8080667176455, 5539.612227144061, 5558.0, 5230.607704422107, 5259.263703081495, 5269.972355390815, 5301.602379821452, 5389.0, 4930.334404855299, 5196.350456192976, 5228.869278267922, 5235.712035837913, 5252.2646829444675, 5423.28504974094, 5431.750697339555, 5441.945580544144, 5468.671879687232, 5524.58565607573, 3970.344893092723, 3991.2217117661776, 3996.500437445319, 4030.2544029354663, 4035.9596404035583, 4941.703497790144, 5274.841754747364, 5283.286756287852, 5286.497782710637, 5679.251693882018, 5643.717814109196, 5753.309602847685, 5781.0, 5838.701133859356, 5879.617824841372, 5866.208061911588, 5877.265341832271, 5883.264591926009, 5892.440218179266, 5899.528037757055, 5601.971990140023, 5657.773689052392, 5732.283464566929, 5739.2252045973255, 5783.3638299787835, 5757.625754325955, 5818.0, 5880.0, 5894.793682221101, 5922.1412895130825, 5556.472135147154, 5676.858078548069, 5704.686242256716, 5724.914126288192, 5769.74036168365], "value": 4287.221702055576, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.0989856715496, 785.8467598818229, 788.7160622176016, 795.4233180943817, 845.3152946113645, 14429.278536073194, 15331.156786376772, 15405.6148596285, 15619.531414057568, 16478.835211647878, 16595.253213605418, 17571.91214043931, 17573.91213043933, 17576.560585985357, 17582.648347033053, 734.383118180728, 893.2050475077186, 895.8880139982502, 899.928005759541, 900.2078171209326, 861.6682577207757, 882.642529775442, 889.3729920406098, 895.6283142495856, 895.7044175422128, 931.6413180925325, 932.4591736792644, 934.1125930366179, 935.4995077633467, 936.2042264075516, 768.2509553185655, 777.8288776469168, 920.0431551186758, 927.554773708617, 927.8237134944372, 586.7418335932219, 668.4485299627827, 675.7533500272415, 681.151965802579, 690.3234829866708, 16443.342266309333, 16446.424375146933, 16480.17599120051, 16523.66952660952, 16547.172641367884, 14263.429462821474, 14610.70778584433, 14713.337899794535, 14779.926100369537, 14786.852131478656, 13149.60551183466, 13429.39567719455, 13573.796393054112, 13779.242141682204, 14050.43798248069, 4086.734362266443, 4128.896777580554, 4148.8340466381305, 4155.252054630166, 4268.850590229358, 81975.5901220496, 82499.0, 82609.9999999994, 85022.57488712517, 85833.57083214607, 11889.404135141827, 12074.27554346742, 12151.270923744603, 12215.389230538438, 12363.75272494545, 22086.0, 22105.33683989483, 22127.668084978737, 22128.0, 22164.556708865734, 9161.083891610919, 9398.483083430478, 9454.0, 9473.147416732389, 9512.809743805085, 5288.286081379041, 5297.867553311198, 5392.892142157213, 5416.431274716098, 5517.2551705520045, 4042.797860106983, 4093.529244136903, 4155.127423241153, 4162.0, 4166.604172603597, 689.3141324382171, 693.4209934704477, 695.0686080651951, 695.537467584061, 701.9894701579481, 701.9786211062993, 758.6320634492267, 808.4906508899473, 818.4107442641289, 819.692615269274, 10780.46097695112, 10846.728831779112, 10900.563977440814, 10908.836367454494, 11194.776104478027, 731.8170457385654, 783.5494590610422, 786.5241528875063, 802.0856223904754, 802.334062727946, 5126.436092029874, 5277.868053298698, 5283.973580132151, 5304.310439642822, 5309.787608495617, 119776.40111799385, 121761.0, 122824.0, 124500.37749811374, 126931.36534317453, 99939.0, 105800.47099764606, 107965.4601727002, 108469.0, 108905.45547272213, 92429.53785231165, 94732.0526794737, 96054.0, 96884.0, 97112.51443742737, 10284.627335378289, 10320.277580569287, 10411.323263987817, 10452.634157804441, 10619.415932123808, 55281.0, 55482.72258638681, 56070.43929560815, 56278.15582766344, 56805.0, 71664.64167679129, 77571.6121419412, 77639.0, 78691.60654196692, 78729.60635197017, 13047.478100875858, 13049.28228947417, 13116.409761560562, 13203.867961320264, 13449.529266475627, 15393.615159621098, 15673.216339182767, 16568.17159142062, 16677.749833752747, 17012.404565840137, 14718.0, 14784.852151478348, 14922.850771492145, 15089.24553772285, 15699.979501332376, 14981.325840336993, 15153.469628562947, 15168.696626067196, 15331.080135191924, 15714.13572253537, 14516.782248266074, 15096.320665570296, 15198.544043678707, 15489.380424782883, 15918.0, 10766.784664306826, 10807.243492955418, 10865.837012444661, 10912.127029837753, 11755.059595232531, 11199.66401007971, 11514.654560363202, 11627.083302155483, 11791.646250612495, 11899.405029748652], "value": 8185.100551153532, "unit": "score"}], "name": "dromaeo_css", "value": 7586.9233117678095, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 102.0, 110.0, 111.0, 110.0, 109.0, 111.0, 109.0, 110.0, 111.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 128.0, 172.0, 124.0, 126.0, 152.0, 131.0, 166.0, 122.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 162.0, 163.0, 161.0, 161.0, 162.0, 163.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 107.0, 75.0, 75.0, 76.0, 76.0, 101.0, 74.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 109.0, 83.0, 83.0, 84.0, 118.0, 84.0, 85.0, 122.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 51.0, 51.0, 51.0, 54.0, 51.0, 53.0, 51.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 79.0, 76.0, 77.0, 77.0, 78.0, 78.0, 118.0, 77.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 122.0, 118.0, 120.0, 115.0, 119.0, 118.0, 120.0, 120.0], "value": 119.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 155.0, 160.0, 158.0, 153.0, 154.0, 156.0, 156.0, 152.0, 156.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 64.0, 61.0, 61.0, 61.0, 60.0, 62.0, 71.0, 60.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1473.2, "alertThreshold": 2.0}]} 08:45:38 INFO - Return code: 0 08:45:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:45:38 ERROR - # TBPL SUCCESS # 08:45:38 INFO - Running post-action listener: _resource_record_post_action 08:45:38 INFO - ##### 08:45:38 INFO - ##### Finished run-tests step (success) 08:45:38 INFO - ##### 08:45:38 INFO - Running post-run listener: _resource_record_post_run 08:45:38 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504143872; Read time: 8; Write time: 474276 08:45:38 INFO - TinderboxPrint: CPU usage
13.2% 08:45:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 08:45:38 INFO - TinderboxPrint: I/O write bytes / time
504,143,872 / 474,276 08:45:38 INFO - TinderboxPrint: CPU idle
8,252.0 (86.8%) 08:45:38 INFO - TinderboxPrint: CPU user
1,206.5 (12.7%) 08:45:38 INFO - TinderboxPrint: Swap in / out
0 / 0 08:45:38 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252538880; Read time: 0; Write time: 301792 08:45:38 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251604992; Read time: 8; Write time: 172484 08:45:39 INFO - Running post-run listener: _upload_blobber_files 08:45:39 INFO - Blob upload gear active. 08:45:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:45:39 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 08:45:39 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:45:39 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:45:39 INFO - (blobuploader) - INFO - Open directory for files ... 08:45:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:45:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:45:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:43 INFO - (blobuploader) - INFO - Done attempting. 08:45:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:45:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:45:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:43 INFO - (blobuploader) - INFO - Done attempting. 08:45:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:45:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:45:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:45 INFO - (blobuploader) - INFO - Done attempting. 08:45:45 INFO - (blobuploader) - INFO - Iteration through files over. 08:45:45 INFO - Return code: 0 08:45:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:45:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:45:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/572b191d8ef01e8e7edd2b990199aa17c546c6273b2d8ad888f87fb0ab47fde0cf3290e7550329bec5c7be90c2dd2b18f71fe4e388c4b674589fbdd56024d645", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec13f61cd3a71ede72824ba2d0411e6d62b0e57f8737c7f57f6c59160db012e2c83975715c6b8604d7debd2d3356749fa0da8efd9cdb4247766c4ff56ceb2926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b9e19784f1a38b5c86391dbb602961847fb16470701bd2cce4e0e1ea594bbac539f37ca7555ba6a36546aa686af2bde4deaccd6ea5a06708bbe32050204ab61"} 08:45:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:45:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:45:45 INFO - Contents: 08:45:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/572b191d8ef01e8e7edd2b990199aa17c546c6273b2d8ad888f87fb0ab47fde0cf3290e7550329bec5c7be90c2dd2b18f71fe4e388c4b674589fbdd56024d645", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec13f61cd3a71ede72824ba2d0411e6d62b0e57f8737c7f57f6c59160db012e2c83975715c6b8604d7debd2d3356749fa0da8efd9cdb4247766c4ff56ceb2926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b9e19784f1a38b5c86391dbb602961847fb16470701bd2cce4e0e1ea594bbac539f37ca7555ba6a36546aa686af2bde4deaccd6ea5a06708bbe32050204ab61"} 08:45:45 INFO - Running post-run listener: copy_logs_to_upload_dir 08:45:45 INFO - Copying logs to upload dir... 08:45:45 INFO - mkdir: /builds/slave/test/build/upload/logs 08:45:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.019046 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-06-21 08:45:44.381496) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 08:45:44.384670) ========= 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=d2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/572b191d8ef01e8e7edd2b990199aa17c546c6273b2d8ad888f87fb0ab47fde0cf3290e7550329bec5c7be90c2dd2b18f71fe4e388c4b674589fbdd56024d645", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec13f61cd3a71ede72824ba2d0411e6d62b0e57f8737c7f57f6c59160db012e2c83975715c6b8604d7debd2d3356749fa0da8efd9cdb4247766c4ff56ceb2926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b9e19784f1a38b5c86391dbb602961847fb16470701bd2cce4e0e1ea594bbac539f37ca7555ba6a36546aa686af2bde4deaccd6ea5a06708bbe32050204ab61"} build_url:https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011447 build_url: 'https://queue.taskcluster.net/v1/task/KvE3NooERwSlKrl417Cywg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/572b191d8ef01e8e7edd2b990199aa17c546c6273b2d8ad888f87fb0ab47fde0cf3290e7550329bec5c7be90c2dd2b18f71fe4e388c4b674589fbdd56024d645", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec13f61cd3a71ede72824ba2d0411e6d62b0e57f8737c7f57f6c59160db012e2c83975715c6b8604d7debd2d3356749fa0da8efd9cdb4247766c4ff56ceb2926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b9e19784f1a38b5c86391dbb602961847fb16470701bd2cce4e0e1ea594bbac539f37ca7555ba6a36546aa686af2bde4deaccd6ea5a06708bbe32050204ab61"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 08:45:44.410233) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:45:44.410536) ========= 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=d2a8c569965ab8367867b893000001a1-1466519972.798262-1454227926 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 08:45:44.494108) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 08:45:44.494452) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 08:45:44.494720) ========= ========= Total master_lag: 0.17 =========