builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1468665120.22 results: success (0) buildid: 20160716005451 builduid: 0fe9a1aa1adb4562ad44f560f82d81a1 revision: 9538d7587ec9ccb4b80ff33bf63b4699eb63298a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.223578) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.224014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.224301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.253119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.253520) ========= 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=df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-16 03:32: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% 225M=0s 2016-07-16 03:32:00 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132607 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.401559) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.402051) ========= 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=df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019454 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.455123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:00.455711) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9538d7587ec9ccb4b80ff33bf63b4699eb63298a --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 9538d7587ec9ccb4b80ff33bf63b4699eb63298a --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=df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-16 03:32:00,906 truncating revision to first 12 chars 2016-07-16 03:32:00,906 Setting DEBUG logging. 2016-07-16 03:32:00,906 attempt 1/10 2016-07-16 03:32:00,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9538d7587ec9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-16 03:32:01,315 unpacking tar archive at: mozilla-aurora-9538d7587ec9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.838281 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:01.309299) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:01.309602) ========= script_repo_revision: 9538d7587ec9ccb4b80ff33bf63b4699eb63298a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:01.310024) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:01.310307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 03:32:01.326693) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-07-16 03:32:01.327108) ========= /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=df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:32:01 INFO - MultiFileLogger online at 20160716 03:32:01 in /builds/slave/test 03:32: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 03:32:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:32:01 INFO - {'append_to_log': False, 03:32:01 INFO - 'base_work_dir': '/builds/slave/test', 03:32:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:32:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:32:01 INFO - 'branch': 'Mozilla-Aurora', 03:32:01 INFO - 'buildbot_json_path': 'buildprops.json', 03:32:01 INFO - 'config_files': ('talos/linux_config.py',), 03:32:01 INFO - 'default_actions': ('clobber', 03:32:01 INFO - 'read-buildbot-config', 03:32:01 INFO - 'download-and-extract', 03:32:01 INFO - 'populate-webroot', 03:32:01 INFO - 'create-virtualenv', 03:32:01 INFO - 'install', 03:32:01 INFO - 'run-tests'), 03:32:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:32:01 INFO - 'download_minidump_stackwalk': True, 03:32:01 INFO - 'download_symbols': 'ondemand', 03:32:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:32:01 INFO - 'tooltool.py': '/tools/tooltool.py', 03:32:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:32:01 INFO - '/tools/misc-python/virtualenv.py')}, 03:32:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:32:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:32:01 INFO - 'installer_path': 'installer.exe', 03:32:01 INFO - 'log_level': 'info', 03:32:01 INFO - 'log_name': 'talos', 03:32:01 INFO - 'log_to_console': True, 03:32:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:32:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:32:01 INFO - 'opt_config_files': (), 03:32:01 INFO - 'pip_index': False, 03:32:01 INFO - 'sps_profile': False, 03:32:01 INFO - 'sps_profile_interval': 0, 03:32:01 INFO - 'suite': 'dromaeojs', 03:32:01 INFO - 'system_bits': '32', 03:32:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:32:01 INFO - 'title': 'talos-linux64-ix-021', 03:32:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:32:01 INFO - 'use_talos_json': True, 03:32:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:32:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:32:01 INFO - 'work_dir': 'build'} 03:32:01 INFO - ##### 03:32:01 INFO - ##### Running clobber step. 03:32:01 INFO - ##### 03:32:01 INFO - Running pre-action listener: _resource_record_pre_action 03:32:01 INFO - Running main action method: clobber 03:32:01 INFO - rmtree: /builds/slave/test/build 03:32:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:32:02 INFO - Running post-action listener: _resource_record_post_action 03:32:02 INFO - ##### 03:32:02 INFO - ##### Finished clobber step (success) 03:32:02 INFO - ##### 03:32:02 INFO - ##### 03:32:02 INFO - ##### Running read-buildbot-config step. 03:32:02 INFO - ##### 03:32:02 INFO - Running pre-action listener: _resource_record_pre_action 03:32:02 INFO - Running main action method: read_buildbot_config 03:32:02 INFO - Using buildbot properties: 03:32:02 INFO - { 03:32:02 INFO - "project": "", 03:32:02 INFO - "product": "firefox", 03:32:02 INFO - "script_repo_revision": "production", 03:32:02 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:32:02 INFO - "repository": "", 03:32:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:32:02 INFO - "buildid": "20160716005451", 03:32:02 INFO - "pgo_build": "True", 03:32:02 INFO - "basedir": "/builds/slave/test", 03:32:02 INFO - "buildnumber": 371, 03:32:02 INFO - "slavename": "talos-linux64-ix-021", 03:32:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:32:02 INFO - "platform": "ubuntu64_hw", 03:32:02 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:32:02 INFO - "branch": "mozilla-aurora", 03:32:02 INFO - "repo_path": "releases/mozilla-aurora", 03:32:02 INFO - "revision": "9538d7587ec9ccb4b80ff33bf63b4699eb63298a", 03:32:02 INFO - "stage_platform": "linux64-pgo", 03:32:02 INFO - "builduid": "0fe9a1aa1adb4562ad44f560f82d81a1", 03:32:02 INFO - "slavebuilddir": "test" 03:32:02 INFO - } 03:32:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:32:02 INFO - Running post-action listener: _resource_record_post_action 03:32:02 INFO - ##### 03:32:02 INFO - ##### Finished read-buildbot-config step (success) 03:32:02 INFO - ##### 03:32:02 INFO - ##### 03:32:02 INFO - ##### Running download-and-extract step. 03:32:02 INFO - ##### 03:32:02 INFO - Running pre-action listener: _resource_record_pre_action 03:32:02 INFO - Running main action method: download_and_extract 03:32:02 INFO - mkdir: /builds/slave/test/build/tests 03:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:02 INFO - https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:32:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:32:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:32:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:03 INFO - Downloaded 1361 bytes. 03:32:03 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:32:03 INFO - Using the following test package requirements: 03:32:03 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:32:03 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:32:03 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:32:03 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'jsshell-linux-x86_64.zip'], 03:32:03 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:32:03 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:32:03 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:32:03 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:32:03 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:32:03 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:03 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:32:03 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:32: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')]} 03:32:03 INFO - https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:32:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:05 INFO - Downloaded 24490025 bytes. 03:32:05 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'] 03:32:05 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 03:32:06 INFO - Return code: 0 03:32:06 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 03:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:06 INFO - https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:32:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:32:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:15 INFO - Downloaded 24490025 bytes. 03:32:15 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'] 03:32:15 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 03:32:16 INFO - Return code: 0 03:32:16 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')]} 03:32:16 INFO - https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:32:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:32:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:32:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:17 INFO - Downloaded 10833369 bytes. 03:32:17 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'] 03:32:17 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 03:32:17 INFO - Return code: 0 03:32:17 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')]} 03:32:17 INFO - https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:32:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I-p95xQwSlmebYH2xCgUcw/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 03:32:19 INFO - Downloaded 63636828 bytes. 03:32:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:19 INFO - mkdir: /builds/slave/test/properties 03:32:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:32:19 INFO - Writing to file /builds/slave/test/properties/build_url 03:32:19 INFO - Contents: 03:32:19 INFO - build_url:https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:32:19 INFO - Running post-action listener: _resource_record_post_action 03:32:20 INFO - Running post-action listener: set_extra_try_arguments 03:32:20 INFO - ##### 03:32:20 INFO - ##### Finished download-and-extract step (success) 03:32:20 INFO - ##### 03:32:20 INFO - ##### 03:32:20 INFO - ##### Running populate-webroot step. 03:32:20 INFO - ##### 03:32:20 INFO - Running pre-action listener: _resource_record_pre_action 03:32:20 INFO - Running main action method: populate_webroot 03:32:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:32:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:32:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:32:20 INFO - '--tppagecycles', 03:32:20 INFO - '7'], 03:32:20 INFO - 'tests': ['tsvgm']}}, 03:32:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:32:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:32:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:32:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:32:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:32:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['damp', 'tps']}, 03:32:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'tests': ['damp', 'tps']}, 03:32:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['dromaeo_dom']}, 03:32:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:32:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['a11yr', 03:32:20 INFO - 'ts_paint', 03:32:20 INFO - 'tpaint', 03:32:20 INFO - 'sessionrestore', 03:32:20 INFO - 'sessionrestore_no_auto_restore', 03:32:20 INFO - 'tabpaint']}, 03:32:20 INFO - 'other-e10s': {'tests': ['a11yr', 03:32:20 INFO - 'ts_paint', 03:32:20 INFO - 'tpaint', 03:32:20 INFO - 'sessionrestore', 03:32:20 INFO - 'sessionrestore_no_auto_restore', 03:32:20 INFO - 'tabpaint']}, 03:32:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:32:20 INFO - 'ts_paint', 03:32:20 INFO - 'tpaint', 03:32:20 INFO - 'sessionrestore', 03:32:20 INFO - 'sessionrestore_no_auto_restore', 03:32:20 INFO - 'tabpaint']}, 03:32:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:32:20 INFO - 'ts_paint', 03:32:20 INFO - 'tpaint', 03:32:20 INFO - 'sessionrestore', 03:32:20 INFO - 'sessionrestore_no_auto_restore', 03:32:20 INFO - 'tabpaint']}, 03:32:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['a11yr', 03:32:20 INFO - 'ts_paint', 03:32:20 INFO - 'tpaint', 03:32:20 INFO - 'sessionrestore', 03:32:20 INFO - 'sessionrestore_no_auto_restore', 03:32:20 INFO - 'tabpaint']}, 03:32:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['a11yr', 03:32:20 INFO - 'ts_paint', 03:32:20 INFO - 'tpaint', 03:32:20 INFO - 'sessionrestore', 03:32:20 INFO - 'sessionrestore_no_auto_restore', 03:32:20 INFO - 'tabpaint']}, 03:32:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['tsvgx', 03:32:20 INFO - 'tsvgr_opacity', 03:32:20 INFO - 'tart', 03:32:20 INFO - 'tscrollx', 03:32:20 INFO - 'cart']}, 03:32:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:32:20 INFO - 'tsvgr_opacity', 03:32:20 INFO - 'tart', 03:32:20 INFO - 'tscrollx', 03:32:20 INFO - 'cart']}, 03:32:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'talos_options': ['--disable-e10s'], 03:32:20 INFO - 'tests': ['tp5o']}, 03:32:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'tests': ['tp5o']}, 03:32:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'talos_options': ['--disable-e10s', 03:32:20 INFO - '--xperf_path', 03:32:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:20 INFO - 'tests': ['tp5n']}, 03:32:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:32:20 INFO - 'talos_options': ['--xperf_path', 03:32:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:32:20 INFO - 'tests': ['tp5n']}}, 03:32:20 INFO - 'talos.zip': {'path': '', 03:32:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:32:20 INFO - Running post-action listener: _resource_record_post_action 03:32:20 INFO - ##### 03:32:20 INFO - ##### Finished populate-webroot step (success) 03:32:20 INFO - ##### 03:32:20 INFO - ##### 03:32:20 INFO - ##### Running create-virtualenv step. 03:32:20 INFO - ##### 03:32:20 INFO - Running pre-action listener: _resource_record_pre_action 03:32:20 INFO - Running main action method: create_virtualenv 03:32:20 INFO - Creating virtualenv /builds/slave/test/build/venv 03:32:20 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 03:32:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:32:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:32:20 INFO - Using real prefix '/usr' 03:32:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:32:20 INFO - Installing distribute.............................................................................................................................................................................................done. 03:32:22 INFO - Installing pip.................done. 03:32:22 INFO - Return code: 0 03:32:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:32:22 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')]} 03:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:22 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')]} 03:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:22 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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32:22 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 03:32:22 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 03:32:22 INFO - Using env: {'DISPLAY': ':0', 03:32:22 INFO - 'HOME': '/home/cltbld', 03:32:22 INFO - 'LANG': 'en_US.UTF-8', 03:32:22 INFO - 'LANGUAGE': 'en_US:en', 03:32:22 INFO - 'LOGNAME': 'cltbld', 03:32:22 INFO - 'MAIL': '/var/mail/cltbld', 03:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:22 INFO - 'MOZ_NO_REMOTE': '1', 03:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:22 INFO - 'NO_EM_RESTART': '1', 03:32:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:22 INFO - 'PWD': '/builds/slave/test', 03:32:22 INFO - 'SHELL': '/bin/bash', 03:32:22 INFO - 'SHLVL': '1', 03:32:22 INFO - 'TERM': 'linux', 03:32:22 INFO - 'TMOUT': '86400', 03:32:22 INFO - 'USER': 'cltbld', 03:32:22 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:22 INFO - '_': '/tools/buildbot/bin/python'} 03:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:22 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 03:32:22 INFO - Cleaning up... 03:32:22 INFO - Return code: 0 03:32:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:32:22 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')]} 03:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:22 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')]} 03:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:22 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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32:22 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 03:32:22 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 03:32:22 INFO - Using env: {'DISPLAY': ':0', 03:32:22 INFO - 'HOME': '/home/cltbld', 03:32:22 INFO - 'LANG': 'en_US.UTF-8', 03:32:22 INFO - 'LANGUAGE': 'en_US:en', 03:32:22 INFO - 'LOGNAME': 'cltbld', 03:32:22 INFO - 'MAIL': '/var/mail/cltbld', 03:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:22 INFO - 'MOZ_NO_REMOTE': '1', 03:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:22 INFO - 'NO_EM_RESTART': '1', 03:32:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:22 INFO - 'PWD': '/builds/slave/test', 03:32:22 INFO - 'SHELL': '/bin/bash', 03:32:22 INFO - 'SHLVL': '1', 03:32:22 INFO - 'TERM': 'linux', 03:32:22 INFO - 'TMOUT': '86400', 03:32:22 INFO - 'USER': 'cltbld', 03:32:22 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:22 INFO - '_': '/tools/buildbot/bin/python'} 03:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:23 INFO - Downloading/unpacking psutil>=3.1.1 03:32:23 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 03:32:23 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 03:32:23 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 03:32:23 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 03:32:23 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 03:32:23 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 03:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:32:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:25 INFO - Installing collected packages: psutil 03:32:25 INFO - Running setup.py install for psutil 03:32:25 INFO - building 'psutil._psutil_linux' extension 03:32:25 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 03:32:25 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 03:32:25 INFO - building 'psutil._psutil_posix' extension 03:32:25 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 03:32:25 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 03:32:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:32:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:32:25 INFO - Successfully installed psutil 03:32:25 INFO - Cleaning up... 03:32:25 INFO - Return code: 0 03:32:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:32: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 mozsystemmonitor==0.3 03:32:25 INFO - Using env: {'DISPLAY': ':0', 03:32:25 INFO - 'HOME': '/home/cltbld', 03:32:25 INFO - 'LANG': 'en_US.UTF-8', 03:32:25 INFO - 'LANGUAGE': 'en_US:en', 03:32:25 INFO - 'LOGNAME': 'cltbld', 03:32:25 INFO - 'MAIL': '/var/mail/cltbld', 03:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:25 INFO - 'MOZ_NO_REMOTE': '1', 03:32:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:25 INFO - 'NO_EM_RESTART': '1', 03:32:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:25 INFO - 'PWD': '/builds/slave/test', 03:32:25 INFO - 'SHELL': '/bin/bash', 03:32:25 INFO - 'SHLVL': '1', 03:32:25 INFO - 'TERM': 'linux', 03:32:25 INFO - 'TMOUT': '86400', 03:32:25 INFO - 'USER': 'cltbld', 03:32:25 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:25 INFO - '_': '/tools/buildbot/bin/python'} 03:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:32:27 INFO - Installing collected packages: mozsystemmonitor 03:32:27 INFO - Running setup.py install for mozsystemmonitor 03:32:27 INFO - Successfully installed mozsystemmonitor 03:32:27 INFO - Cleaning up... 03:32:28 INFO - Return code: 0 03:32:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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', '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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:32: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 jsonschema==2.5.1 03:32:28 INFO - Using env: {'DISPLAY': ':0', 03:32:28 INFO - 'HOME': '/home/cltbld', 03:32:28 INFO - 'LANG': 'en_US.UTF-8', 03:32:28 INFO - 'LANGUAGE': 'en_US:en', 03:32:28 INFO - 'LOGNAME': 'cltbld', 03:32:28 INFO - 'MAIL': '/var/mail/cltbld', 03:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:28 INFO - 'MOZ_NO_REMOTE': '1', 03:32:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:28 INFO - 'NO_EM_RESTART': '1', 03:32:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:28 INFO - 'PWD': '/builds/slave/test', 03:32:28 INFO - 'SHELL': '/bin/bash', 03:32:28 INFO - 'SHLVL': '1', 03:32:28 INFO - 'TERM': 'linux', 03:32:28 INFO - 'TMOUT': '86400', 03:32:28 INFO - 'USER': 'cltbld', 03:32:28 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:28 INFO - '_': '/tools/buildbot/bin/python'} 03:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:28 INFO - Downloading/unpacking jsonschema==2.5.1 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:32:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:30 INFO - Downloading functools32-3.2.3-2.tar.gz 03:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:32:30 INFO - warning: no files found matching '*.txt' 03:32:30 INFO - no previously-included directories found matching 'build' 03:32:30 INFO - no previously-included directories found matching 'dist' 03:32:30 INFO - no previously-included directories found matching '.git*' 03:32:30 INFO - Installing collected packages: jsonschema, functools32 03:32:30 INFO - Running setup.py install for functools32 03:32:30 INFO - warning: no files found matching '*.txt' 03:32:30 INFO - no previously-included directories found matching 'build' 03:32:30 INFO - no previously-included directories found matching 'dist' 03:32:30 INFO - no previously-included directories found matching '.git*' 03:32:30 INFO - Successfully installed jsonschema functools32 03:32:30 INFO - Cleaning up... 03:32:30 INFO - Return code: 0 03:32:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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', '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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:32: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 blobuploader==1.2.4 03:32:30 INFO - Using env: {'DISPLAY': ':0', 03:32:30 INFO - 'HOME': '/home/cltbld', 03:32:30 INFO - 'LANG': 'en_US.UTF-8', 03:32:30 INFO - 'LANGUAGE': 'en_US:en', 03:32:30 INFO - 'LOGNAME': 'cltbld', 03:32:30 INFO - 'MAIL': '/var/mail/cltbld', 03:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:30 INFO - 'MOZ_NO_REMOTE': '1', 03:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:30 INFO - 'NO_EM_RESTART': '1', 03:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:30 INFO - 'PWD': '/builds/slave/test', 03:32:30 INFO - 'SHELL': '/bin/bash', 03:32:30 INFO - 'SHLVL': '1', 03:32:30 INFO - 'TERM': 'linux', 03:32:30 INFO - 'TMOUT': '86400', 03:32:30 INFO - 'USER': 'cltbld', 03:32:30 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:30 INFO - '_': '/tools/buildbot/bin/python'} 03:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:30 INFO - Downloading/unpacking blobuploader==1.2.4 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:32 INFO - Downloading blobuploader-1.2.4.tar.gz 03:32:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:32:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:32:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:33 INFO - Downloading docopt-0.6.1.tar.gz 03:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:32:33 INFO - Installing collected packages: blobuploader, requests, docopt 03:32:33 INFO - Running setup.py install for blobuploader 03:32:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:32:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:32:33 INFO - Running setup.py install for requests 03:32:33 INFO - Running setup.py install for docopt 03:32:33 INFO - Successfully installed blobuploader requests docopt 03:32:33 INFO - Cleaning up... 03:32:34 INFO - Return code: 0 03:32:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:34 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')]} 03:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:34 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')]} 03:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:34 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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32:34 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 03:32:34 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 03:32:34 INFO - Using env: {'DISPLAY': ':0', 03:32:34 INFO - 'HOME': '/home/cltbld', 03:32:34 INFO - 'LANG': 'en_US.UTF-8', 03:32:34 INFO - 'LANGUAGE': 'en_US:en', 03:32:34 INFO - 'LOGNAME': 'cltbld', 03:32:34 INFO - 'MAIL': '/var/mail/cltbld', 03:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:34 INFO - 'MOZ_NO_REMOTE': '1', 03:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:34 INFO - 'NO_EM_RESTART': '1', 03:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:34 INFO - 'PWD': '/builds/slave/test', 03:32:34 INFO - 'SHELL': '/bin/bash', 03:32:34 INFO - 'SHLVL': '1', 03:32:34 INFO - 'TERM': 'linux', 03:32:34 INFO - 'TMOUT': '86400', 03:32:34 INFO - 'USER': 'cltbld', 03:32:34 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:34 INFO - '_': '/tools/buildbot/bin/python'} 03:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:34 INFO - Running setup.py (path:/tmp/pip-sTRPPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:34 INFO - Running setup.py (path:/tmp/pip-DfScrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:34 INFO - Running setup.py (path:/tmp/pip-i14bLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:34 INFO - Running setup.py (path:/tmp/pip-AGYWO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:34 INFO - Running setup.py (path:/tmp/pip-dVNYes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:34 INFO - Running setup.py (path:/tmp/pip-WsddwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:34 INFO - Running setup.py (path:/tmp/pip-MmGfjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:34 INFO - Running setup.py (path:/tmp/pip-mHV6GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:34 INFO - Running setup.py (path:/tmp/pip-rEQjM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:34 INFO - Running setup.py (path:/tmp/pip-Uogl2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:34 INFO - Running setup.py (path:/tmp/pip-OGxHxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:35 INFO - Running setup.py (path:/tmp/pip-9tvrun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:35 INFO - Running setup.py (path:/tmp/pip-NsVwKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:35 INFO - Running setup.py (path:/tmp/pip-lU83AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:35 INFO - Running setup.py (path:/tmp/pip-8Q1hQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:35 INFO - Running setup.py (path:/tmp/pip-rtEcpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:35 INFO - Running setup.py (path:/tmp/pip-4TvNTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:32:35 INFO - Running setup.py install for manifestparser 03:32:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:32:35 INFO - Running setup.py install for mozcrash 03:32:35 INFO - Running setup.py install for mozdebug 03:32:35 INFO - Running setup.py install for mozdevice 03:32:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:32:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:32:35 INFO - Running setup.py install for mozfile 03:32:36 INFO - Running setup.py install for mozhttpd 03:32:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Running setup.py install for mozinfo 03:32:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Running setup.py install for mozInstall 03:32:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Running setup.py install for mozleak 03:32:36 INFO - Running setup.py install for mozlog 03:32:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Running setup.py install for moznetwork 03:32:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Running setup.py install for mozprocess 03:32:36 INFO - Running setup.py install for mozprofile 03:32:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Running setup.py install for mozrunner 03:32:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:32:36 INFO - Running setup.py install for mozscreenshot 03:32:37 INFO - Running setup.py install for moztest 03:32:37 INFO - Running setup.py install for mozversion 03:32:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:32:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:32:37 INFO - Cleaning up... 03:32:37 INFO - Return code: 0 03:32:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:37 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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32:37 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 03:32:37 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 03:32:37 INFO - Using env: {'DISPLAY': ':0', 03:32:37 INFO - 'HOME': '/home/cltbld', 03:32:37 INFO - 'LANG': 'en_US.UTF-8', 03:32:37 INFO - 'LANGUAGE': 'en_US:en', 03:32:37 INFO - 'LOGNAME': 'cltbld', 03:32:37 INFO - 'MAIL': '/var/mail/cltbld', 03:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:37 INFO - 'MOZ_NO_REMOTE': '1', 03:32:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:37 INFO - 'NO_EM_RESTART': '1', 03:32:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:37 INFO - 'PWD': '/builds/slave/test', 03:32:37 INFO - 'SHELL': '/bin/bash', 03:32:37 INFO - 'SHLVL': '1', 03:32:37 INFO - 'TERM': 'linux', 03:32:37 INFO - 'TMOUT': '86400', 03:32:37 INFO - 'USER': 'cltbld', 03:32:37 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:37 INFO - '_': '/tools/buildbot/bin/python'} 03:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:37 INFO - Running setup.py (path:/tmp/pip-6Gu2zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:37 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)) 03:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:37 INFO - Running setup.py (path:/tmp/pip-EuImTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:37 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)) 03:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:37 INFO - Running setup.py (path:/tmp/pip-ZduQVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:37 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)) 03:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:37 INFO - Running setup.py (path:/tmp/pip-bG5V8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:37 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)) 03:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:37 INFO - Running setup.py (path:/tmp/pip-egK0Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:37 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)) 03:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:37 INFO - Running setup.py (path:/tmp/pip-I15jQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:37 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)) 03:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:37 INFO - Running setup.py (path:/tmp/pip-ZSwGaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:38 INFO - Running setup.py (path:/tmp/pip-rh4UR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:38 INFO - Running setup.py (path:/tmp/pip-kdecSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:38 INFO - Running setup.py (path:/tmp/pip-Cq8Yee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:38 INFO - Running setup.py (path:/tmp/pip-VbYuSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:38 INFO - Running setup.py (path:/tmp/pip-tWDjCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:38 INFO - Running setup.py (path:/tmp/pip-vIJzJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:38 INFO - Running setup.py (path:/tmp/pip-_ABBqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:38 INFO - Running setup.py (path:/tmp/pip-_L8dVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:38 INFO - Running setup.py (path:/tmp/pip-JI_NwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:38 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)) 03:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:38 INFO - Running setup.py (path:/tmp/pip-SaiSSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:38 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)) 03:32:38 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)) 03:32:38 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)) 03:32:38 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)) 03:32:38 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)) 03:32:38 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)) 03:32:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:32:38 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 03:32:38 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 03:32:38 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 03:32:38 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 03:32:38 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 03:32:38 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 03:32:40 INFO - Downloading blessings-1.6.tar.gz 03:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:32:40 INFO - Installing collected packages: blessings 03:32:40 INFO - Running setup.py install for blessings 03:32:40 INFO - Successfully installed blessings 03:32:40 INFO - Cleaning up... 03:32:41 INFO - Return code: 0 03:32:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:32:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:41 INFO - Reading from file tmpfile_stdout 03:32:41 INFO - Current package versions: 03:32:41 INFO - argparse == 1.2.1 03:32:41 INFO - blessings == 1.6 03:32:41 INFO - blobuploader == 1.2.4 03:32:41 INFO - docopt == 0.6.1 03:32:41 INFO - functools32 == 3.2.3-2 03:32:41 INFO - jsonschema == 2.5.1 03:32:41 INFO - manifestparser == 1.1 03:32:41 INFO - mozInstall == 1.12 03:32:41 INFO - mozcrash == 0.17 03:32:41 INFO - mozdebug == 0.1 03:32:41 INFO - mozdevice == 0.48 03:32:41 INFO - mozfile == 1.2 03:32:41 INFO - mozhttpd == 0.7 03:32:41 INFO - mozinfo == 0.9 03:32:41 INFO - mozleak == 0.1 03:32:41 INFO - mozlog == 3.2 03:32:41 INFO - moznetwork == 0.27 03:32:41 INFO - mozprocess == 0.23 03:32:41 INFO - mozprofile == 0.28 03:32:41 INFO - mozrunner == 6.12 03:32:41 INFO - mozscreenshot == 0.1 03:32:41 INFO - mozsystemmonitor == 0.3 03:32:41 INFO - moztest == 0.7 03:32:41 INFO - mozversion == 1.4 03:32:41 INFO - psutil == 3.1.1 03:32:41 INFO - requests == 1.2.3 03:32:41 INFO - wsgiref == 0.1.2 03:32:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:41 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')]} 03:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:41 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')]} 03:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:41 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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32:41 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 03:32:41 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 03:32:41 INFO - Using env: {'DISPLAY': ':0', 03:32:41 INFO - 'HOME': '/home/cltbld', 03:32:41 INFO - 'LANG': 'en_US.UTF-8', 03:32:41 INFO - 'LANGUAGE': 'en_US:en', 03:32:41 INFO - 'LOGNAME': 'cltbld', 03:32:41 INFO - 'MAIL': '/var/mail/cltbld', 03:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:41 INFO - 'MOZ_NO_REMOTE': '1', 03:32:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:41 INFO - 'NO_EM_RESTART': '1', 03:32:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:41 INFO - 'PWD': '/builds/slave/test', 03:32:41 INFO - 'SHELL': '/bin/bash', 03:32:41 INFO - 'SHLVL': '1', 03:32:41 INFO - 'TERM': 'linux', 03:32:41 INFO - 'TMOUT': '86400', 03:32:41 INFO - 'USER': 'cltbld', 03:32:41 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:41 INFO - '_': '/tools/buildbot/bin/python'} 03:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 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)) 03:32:41 INFO - Cleaning up... 03:32:41 INFO - Return code: 0 03:32:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:32:41 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')]} 03:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:41 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')]} 03:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:41 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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32:41 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 03:32:41 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 03:32:41 INFO - Using env: {'DISPLAY': ':0', 03:32:41 INFO - 'HOME': '/home/cltbld', 03:32:41 INFO - 'LANG': 'en_US.UTF-8', 03:32:41 INFO - 'LANGUAGE': 'en_US:en', 03:32:41 INFO - 'LOGNAME': 'cltbld', 03:32:41 INFO - 'MAIL': '/var/mail/cltbld', 03:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:41 INFO - 'MOZ_NO_REMOTE': '1', 03:32:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:41 INFO - 'NO_EM_RESTART': '1', 03:32:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:41 INFO - 'PWD': '/builds/slave/test', 03:32:41 INFO - 'SHELL': '/bin/bash', 03:32:41 INFO - 'SHLVL': '1', 03:32:41 INFO - 'TERM': 'linux', 03:32:41 INFO - 'TMOUT': '86400', 03:32:41 INFO - 'USER': 'cltbld', 03:32:41 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:41 INFO - '_': '/tools/buildbot/bin/python'} 03:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:32:42 INFO - Cleaning up... 03:32:42 INFO - Return code: 0 03:32:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:42 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 0x7ffdad96cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b4350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, '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': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', '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 03:32:42 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 03:32:42 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 03:32:42 INFO - Using env: {'DISPLAY': ':0', 03:32:42 INFO - 'HOME': '/home/cltbld', 03:32:42 INFO - 'LANG': 'en_US.UTF-8', 03:32:42 INFO - 'LANGUAGE': 'en_US:en', 03:32:42 INFO - 'LOGNAME': 'cltbld', 03:32:42 INFO - 'MAIL': '/var/mail/cltbld', 03:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:42 INFO - 'MOZ_NO_REMOTE': '1', 03:32:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:32:42 INFO - 'NO_EM_RESTART': '1', 03:32:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:42 INFO - 'PWD': '/builds/slave/test', 03:32:42 INFO - 'SHELL': '/bin/bash', 03:32:42 INFO - 'SHLVL': '1', 03:32:42 INFO - 'TERM': 'linux', 03:32:42 INFO - 'TMOUT': '86400', 03:32:42 INFO - 'USER': 'cltbld', 03:32:42 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:42 INFO - '_': '/tools/buildbot/bin/python'} 03:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:42 INFO - Downloading/unpacking flake8 03:32:42 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 03:32:42 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 03:32:42 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 03:32:42 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 03:32:42 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 03:32:42 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 03:32:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:32:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:32:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32: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 03:32:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:32:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:32:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:32:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:32:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:32:44 INFO - no previously-included directories found matching 'docs/_build' 03:32:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:32:45 INFO - Running setup.py install for pep8 03:32:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:32:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:32:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:32:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:32:45 INFO - no previously-included directories found matching 'docs/_build' 03:32:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:32:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:32:45 INFO - Cleaning up... 03:32:45 INFO - Return code: 0 03:32:45 INFO - Running post-action listener: _resource_record_post_action 03:32:45 INFO - Running post-action listener: _start_resource_monitoring 03:32:45 INFO - Starting resource monitoring. 03:32:45 INFO - ##### 03:32:45 INFO - ##### Finished create-virtualenv step (success) 03:32:45 INFO - ##### 03:32:45 INFO - ##### 03:32:45 INFO - ##### Running install step. 03:32:45 INFO - ##### 03:32:45 INFO - Running pre-action listener: _resource_record_pre_action 03:32:45 INFO - Running main action method: install 03:32:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:45 INFO - Reading from file tmpfile_stdout 03:32:45 INFO - Detecting whether we're running mozinstall >=1.0... 03:32:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:32:45 INFO - Reading from file tmpfile_stdout 03:32:45 INFO - Output received: 03:32:45 INFO - Usage: mozinstall [options] installer 03:32:45 INFO - Options: 03:32:45 INFO - -h, --help show this help message and exit 03:32:45 INFO - -d DEST, --destination=DEST 03:32:45 INFO - Directory to install application into. [default: 03:32:45 INFO - "/builds/slave/test"] 03:32:45 INFO - --app=APP Application being installed. [default: firefox] 03:32:45 INFO - mkdir: /builds/slave/test/build/application 03:32:45 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'] 03:32:45 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 03:33:01 INFO - Reading from file tmpfile_stdout 03:33:01 INFO - Output received: 03:33:01 INFO - /builds/slave/test/build/application/firefox/firefox 03:33:01 INFO - Running post-action listener: _resource_record_post_action 03:33:01 INFO - ##### 03:33:01 INFO - ##### Finished install step (success) 03:33:01 INFO - ##### 03:33:01 INFO - ##### 03:33:01 INFO - ##### Running run-tests step. 03:33:01 INFO - ##### 03:33:01 INFO - Running pre-action listener: _resource_record_pre_action 03:33:01 INFO - Running main action method: run_tests 03:33:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:33:01 INFO - Python 2.7.3 03:33:01 INFO - Return code: 0 03:33:01 INFO - grabbing minidump binary from tooltool 03:33:01 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')]} 03:33:01 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 0x1a431c0>, '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 0x1a440c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a44550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:33:01 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 03:33:01 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 03:33:01 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 03:33:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:33:01 INFO - Return code: 0 03:33:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:33:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:33:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:33:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:33:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:33:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:33:02 INFO - Return code: 0 03:33:02 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/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 03:33:02 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/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 03:33:02 INFO - Using env: {'DISPLAY': ':0', 03:33:02 INFO - 'HOME': '/home/cltbld', 03:33:02 INFO - 'LANG': 'en_US.UTF-8', 03:33:02 INFO - 'LANGUAGE': 'en_US:en', 03:33:02 INFO - 'LOGNAME': 'cltbld', 03:33:02 INFO - 'MAIL': '/var/mail/cltbld', 03:33:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:33:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:33:02 INFO - 'MOZ_NO_REMOTE': '1', 03:33:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:33:02 INFO - 'NO_EM_RESTART': '1', 03:33:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:33:02 INFO - 'PWD': '/builds/slave/test', 03:33:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:33:02 INFO - 'SHELL': '/bin/bash', 03:33:02 INFO - 'SHLVL': '1', 03:33:02 INFO - 'TERM': 'linux', 03:33:02 INFO - 'TMOUT': '86400', 03:33:02 INFO - 'USER': 'cltbld', 03:33:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488', 03:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:33:02 INFO - '_': '/tools/buildbot/bin/python'} 03:33:02 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/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 03:33:02 INFO - mozversion application_buildid: 20160716005451 03:33:02 INFO - mozversion application_changeset: 9538d7587ec9ccb4b80ff33bf63b4699eb63298a 03:33:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:33:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:33:02 INFO - mozversion application_name: Firefox 03:33:02 INFO - mozversion application_remotingname: firefox-dev 03:33:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:33:02 INFO - mozversion application_vendor: Mozilla 03:33:02 INFO - mozversion application_version: 49.0a2 03:33:02 INFO - mozversion platform_buildid: 20160716005451 03:33:02 INFO - mozversion platform_changeset: 9538d7587ec9ccb4b80ff33bf63b4699eb63298a 03:33:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:33:02 INFO - mozversion platform_version: 49.0a2 03:33:02 INFO - using testdate: 1468665182 03:33:02 INFO - actual date: 1468665182 03:33:02 INFO - starting webserver on 'localhost:43233' 03:33:02 INFO - SUITE-START | Running 2 tests 03:33:02 INFO - TEST-START | dromaeo_css 03:33:02 INFO - operating with platform_type : linux_ 03:33:02 INFO - Initialising browser for dromaeo_css test... 03:33:02 INFO - TEST-INFO | started process 26689 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeCRzFi/profile http://localhost:43233/getInfo.html) 03:33:06 INFO - PROCESS | 26689 | __metrics Screen width/height:1600/1200 03:33:06 INFO - PROCESS | 26689 | colorDepth:24 03:33:06 INFO - PROCESS | 26689 | Browser inner width/height: 1024/697 03:33:06 INFO - PROCESS | 26689 | __metrics 03:33:07 INFO - PROCESS | 26689 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:33:07 ERROR - PROCESS | 26689 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:33:09 INFO - TEST-INFO | 26689: exit 0 03:33:09 INFO - Browser initialized. 03:33:09 INFO - Running cycle 1/1 for dromaeo_css test... 03:33:09 INFO - TEST-INFO | started process 26781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeCRzFi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:33:20 INFO - PROCESS | 26781 | RSS: Main: 145043456 03:33:20 INFO - PROCESS | 26781 | 03:34:11 INFO - PROCESS | 26781 | 1468665251852 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] 03:36:19 INFO - PROCESS | 26781 | Cycle 1(1): loaded http://localhost:43233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43233/tests/dromaeo/cssquery-ext.html) 03:36:20 INFO - PROCESS | 26781 | RSS: Main: 250953728 03:36:20 INFO - PROCESS | 26781 | 03:39:23 INFO - PROCESS | 26781 | Cycle 1(1): loaded http://localhost:43233/tests/dromaeo/cssquery-ext.html (next: http://localhost:43233/tests/dromaeo/cssquery-jquery.html) 03:39:23 INFO - PROCESS | 26781 | RSS: Main: 239374336 03:39:23 INFO - PROCESS | 26781 | 03:42:25 INFO - PROCESS | 26781 | Cycle 1(1): loaded http://localhost:43233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43233/tests/dromaeo/cssquery-mootools.html) 03:42:26 INFO - PROCESS | 26781 | RSS: Main: 228712448 03:42:26 INFO - PROCESS | 26781 | 03:45:28 INFO - PROCESS | 26781 | Cycle 1(1): loaded http://localhost:43233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43233/tests/dromaeo/cssquery-prototype.html) 03:45:28 INFO - PROCESS | 26781 | RSS: Main: 222691328 03:45:28 INFO - PROCESS | 26781 | 03:48:31 INFO - PROCESS | 26781 | Cycle 1(1): loaded http://localhost:43233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43233/tests/dromaeo/cssquery-yui.html) 03:48:32 INFO - PROCESS | 26781 | RSS: Main: 215056384 03:48:32 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | Cycle 1(1): loaded http://localhost:43233/tests/dromaeo/cssquery-yui.html (next: http://localhost:43233/tests/dromaeo/cssquery-dojo.html) 03:51:34 INFO - PROCESS | 26781 | RSS: Main: 261234688 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | __start_tp_report 03:51:34 INFO - PROCESS | 26781 | _x_x_mozilla_page_load 03:51:34 INFO - PROCESS | 26781 | _x_x_mozilla_page_load_details 03:51:34 INFO - PROCESS | 26781 | |i|pagename|runs| 03:51:34 INFO - PROCESS | 26781 | |0;dojo.html;3261.070594880459;3296.4066468035758;3311.337732453509;3322.3355328934217;3351.681590248927;5377.408485066644;5449;5466.562674986001;5468.945310546894;5487.670739755613;5429.592780541456;5455.618106732531;5508.394076651575;5510.834674959748;5535.308086489189;4828.7585620719;4936.629752768547;4992.425871024842;5039.44566097729;5098.209777484496;4789.20978038625;4924.4583095859425;4951;4966.801327946896;4972.776225069862;4904.362432883738;4938.80244790208;4957.975210123962;4960.057589058086;4963.032208719301;5046.924296135561;5052.949470505285;5055.418626857903;5063.3417655704525;5087.440381558025;4841.128596852565;4902.240152776325;4936.086823937547;4986.476419975923;4990.276409920578;5275.709835959021;5292.047431462335;5293.470652934714;5307.814226502092;5335.599830012726;5804.564657650693;5813.069908814612;5838.299404071496;5845.035569131088;5847.619904706223;5735.455131762517;5811;5813.360530341659;5886.911696324559;5888.852778680567;5473.890522189574;5489.560835133179;5514.862128446741;5516.889662206733;5525.143602741622;5801.071828507418;5995.46040856325;5996.430339117776;5996.910046349309;6007.459328660354;5686.43135686428;5729.828105156852;5740.54075673945;5809.622374545641;5829.650220986754;5817.301923769175;5820.941790582125;5825;5905.852353691106;5915.438033386821;5551.167324901297;5660.943390566124;5783.450572195718;5790.218320526759;5795.304563452329;5770.711464426762;5850.034744267234;5853.0635098384055;5861.062230043172;5889.970550147307;4499.505054444008;4507.940633951019;4509.1658043262105;4510.819567217274;4534.070515544306;3903.921921561553;3930.2336044471626;3968.6229808168177;4035.858744943913;4073.144639625712;5422.240886275925;5449.64577302482;5456.91814622781;5462.863428414242;5480.095784195642;5790.797322093791;5803.245578074823;5803.274590676166;5805;5835.79574714883;5266.78932842682;5438.700871452108;5440.727963601804;5443.972780136074;5469.042917489503;5101.566366858859;5101.923471147936;5103.923441148386;5104.157813960729;5115.693058416433;5971.880562388728;5986.970065149733;5987.371326010793;5994;5997.280326360865;5689.914651280152;5707.143928410772;5822.883542329045;5831.0378787499585;5943.138244954457;5932.7033648318275;5933.940660593339;5938.465538101592;5946.375630558729;5950.821475355573;5724.169995350734;5760.135979603093;5760.510356619567;5767.8558036049435;5772.826815195551;5682.176084467646;5768.221290125947;5800.39095894925;5831.1544826000845;5883.94116058834;5723.8569035774435;5726.685032323096;5730.856728581819;5731.254936858349;5735.569832262514;5496.203050557567;5712.857178570569;5731.770729170953;5733.684647344436;5733.971330143323;5455.072637651688;5486.149646804752;5497.230387745799;5553.278073850535;5555.8611034722835;5664.801731939362;5665.518430933252;5686.147077938343;5695.943040569541;5705.372409035086;5433.266509021231;5470.452954704498;5545.417731138334;5557.972210138923;5575.470330318613 03:51:34 INFO - PROCESS | 26781 | |1;ext.html;613.3590398034054;645.1161908185785;657.0472814419094;665.1386454541365;673.7237732529662;33939.66060339396;46859;48085.51914480854;48197.51802481974;48220;49328.75335623317;49746.75126624371;49786.25320620194;49815;49946;8389.622466989;8973.46159230445;9119.860017497813;9130.452172869616;10765.946170269137;10448.111910487618;10842.403667798268;10845.728856778565;10845.83731244028;10848.67453976382;10885.23803333767;10935.28920620161;10935.945320273388;10936.12510999122;10937;8430.62062207199;8622.784430389227;10822.24244302899;10852.565897364175;11122.833157502644;10539.683809485728;10990;11002.064824489928;11006;11007.06439952605;46880;47566;47592;47597;47633.523664763605;3717.423799311084;3879.1853710720784;4329.047609525899;4367.10474352757;4369.4101296325225;36353.091172720626;36752;36887.26225475476;37355.813220934;37779;4629;4635.791389387486;4643.767811609406;4667.393238878924;4698.506656801055;429327;434216;434225;434431;434641;39797.40303895444;40851.59148408537;40870;72363.2763672367;73481;39897.60102398939;40146.39780403296;40228;40253.79873100616;40284.19431611351;17686.823131768517;17871.106444677713;17880;17881.37415190462;17884.821151788317;12541;13449.663758406117;13561.389737461837;13586;13794.586162415144;9561.378510396802;9590.328676992684;9591.808163836684;9616.9038309616;9688.70933871985;3578.248567800739;3609.7473176877365;3662.689100803792;3705.4071348584635;3707.2400157967554;1054.1040115901421;1095.9945200273949;1128.3230061962756;1149.735560821016;1182.8876256755595;35241.295174096886;35291.4706279406;35422.822885885405;35453.468197977054;35568;1226.907981901361;1245.3835351501066;1295.8509771376223;1298.642873209856;1368.3021658954005;18289.634207316045;18568.44294671162;18696.158672859754;18725.25098996025;18753.437396878115;640576;644736;646935;649219;650620;65721;66392.66803665951;66418;66442.66778666172;66489;1318.1432069155126;1434.8780353670054;1442.113100443241;1455.9854401456062;1477.8152730908637;34616;35367.11582210465;35687.64312356843;35696.28607427785;35732.46401304034;53166;53264.73367633292;53496.73251633717;53745.19382209348;53789;477906;478979;480142;480866;482357;28215.71784282213;32716.34567308593;33583.83208083944;33670.3265934675;33999;51874;52692.47307527029;52985.735071324394;53118.46881531135;53878;43492.98476077245;44027.559724402345;44529;44732.32901506545;45327.77336113298;43047;43579.782101089295;43954.34068489039;44223.33664995092;44444;44264.55735442733;44823.77588112038;45652.543474566155;45739;45745.54254457413;37551.62344262034;39330.60669393269;39419.80290098531;39594.60405395909;39858.40212396874;28848.134555963356;29207.707922920497;29325.85337073301;29351.85324073366;29538 03:51:34 INFO - PROCESS | 26781 | |2;jquery.html;432.50046196642876;432.8723026707121;434.55829827764103;438.0472472372589;445.5188396531748;18782;19918.90040549797;20704.068316925735;20733.274335398262;20788;19704.60590788187;20266;20295.391138265877;20305.390838274838;20510.589788204263;5703.828885133442;5879.941200588003;5933.495652869511;5944.256967879015;5960.105984102376;5700.572457065716;5852.853678658025;5863.824085277427;5866.911996320058;5884.176215329857;5802.448767367115;5803.738831752581;5804.245448091742;5805.796797112102;5815.47660710536;5739.512141467981;5832;5834.445727655866;5837.50381217597;5840.941590584103;5009.298698182258;5092.414372347191;5219.895602087975;5246.606504512139;5250.081235783722;20527;21967.890160549094;23331.53336933252;23699;24026.159084432136;2643.537380958344;2704.2428120126397;2755.2147637923094;2823.378856651533;3071.831049292288;9814.705558833246;10013.59945602175;10230.335028223217;11130.721731956684;15492.690146197014;1929.305450037985;1978.5251539630528;2003.8597298189134;2100.1494394770143;2110.1981247126146;84337;87637;90218.5489072557;91123;92132.53933730288;11105.666829995112;11342.376169310603;11607.6517704469;11683.357415342069;12021.158518903767;14697.632559186106;14869.405223791144;14942.626434339229;15083.773743393858;15554.766678499831;8528.147185281547;8590.613422396007;8754.47473151613;8812.77968050791;8839.29285657146;8771.736847894574;8774.508333583306;8796.91203087961;8924.375293729507;8947.865782013276;4452.666050046195;4638.744869032235;4684.250519916797;4691.50739172382;4860.222364421676;1797.4653882954813;1802.6574950759314;1953.90230488475;2093.1941202637095;2108.4518025313223;952.3476418653245;1035.9533820978074;1045.3936716704293;1089.8910108989196;1095.64939219449;19415.3204637837;19520.902395488214;19557.413277601696;19743.21027158926;19901.800981989996;1050.3015494696094;1068.871735391758;1095.687728997232;1100.8293714474269;1119.9328040317607;10740.355578665305;11233.438328083563;11297.774044519228;11306.491207895664;11344.489497972447;541659;544593;557842;558775;568772;99272;101283.49358253309;101679.48219290086;102437;103288.48355758173;1021.8467229915572;1029.5727273181697;1030.5826140413144;1042.0413219837767;1073.6778966309919;17149.542288905584;17307.653846922738;17475;17574.82425175783;17728.734068989233;30767.384652307275;37927.24145517129;42612;43094.78452607717;44221.336679949185;60016.39983600108;61182.38817611767;62569.37430625818;63432.68283658552;64432;9038.141376569216;9081.500517471339;9106.362554621113;9217.170454659114;9375.718728438158;10441.373517588969;13223.3388330585;13662.316884155554;13733.381997810322;13914.582562523141;11796.115291353011;11816.645500634995;12002.279863208236;12043.217190882564;12082.879171208528;11221.663350099183;11306.830397544209;11442.313461192354;11696.473658685209;11975.64073077809;11692.883071169179;11935.462904169159;12075.75848483043;12110.091743119478;12479;9064.138906803842;9162.633494660138;9166.862497062682;12093.697657558632;12666.493340266286;12087.75824483523;12540.310282934526;12697.746045078862;13104.868951310365;13181.275029873448 03:51:34 INFO - PROCESS | 26781 | |3;mootools.html;1388.9166650000993;1570.4895908829633;1597.8322276161002;1795.2639417838689;1917.904104794759;7839.960800195998;9106.954465227673;9393.342466027363;9407.341486095977;9686.07982241686;9316.208122309596;9498.620055197802;9504;9515.571799269032;9517.04829517048;2145.592337455886;2373.6795532603137;2462.384403899025;2467.543504451682;2500.7999360051244;2490.2653717153394;2514.421683012908;2515.0317127905796;2517.471331020479;2517.748225177477;2389.1279682915697;2433.3065076453213;2435.963460548084;2436.7928726058217;2439.73162952076;2060.5054786851124;2111.3032699209225;2282.1213832674375;2371.727251366089;2374.8812559371963;2399.6160614301803;2427.490227052321;2429;2429.149797570854;2429.5383877063477;9422.293328000358;9456.905430945671;9457.3852699575;9465.763355916088;9467.573959171854;5287.021900948298;5307.9734601326745;5396.811111611114;5421.186821976696;5431.8593095450005;3436.347094052147;3524.594671612772;3547.592026916886;3566.2689148724708;3609.3683605368956;1259.8488181418288;1271.8155867399175;1272.5291642092393;1291.5027714330051;1304.4912484131196;12920.676983075404;12931.094823362368;12941.547045853444;12964.286964216964;12972.09195356316;4771.908165906701;4786.712797232177;4894.510548945148;4901.828436004723;4901.950980490221;10651.041406273473;10718.89281107179;10721.249512534203;10724.03483686472;10742.194335424874;5580.469855363734;5583.4137415571595;5586.91619625706;5590.86022849432;5591.664500129924;4444.533324000998;4890.486498917634;5027.497250275016;5031.572316353151;5101.795928162832;4138.048248902769;4188.392683060939;4193.35003074524;4210.85262015828;4226.344916537942;1316.5655333739842;1360.3606305036617;1362.257569624557;1365.7473367427058;1373.979390309126;978.188103873783;988.357567581078;1044.9373037617768;1096.1504833753904;1105.8617672790901;6369;6467.870642587122;6511.446527045159;6574.572652777554;6760.797176084725;1088.4666513408372;1127.1095834290818;1128.503458478267;1161.4599211366756;1164.4294295795169;4383.802728877208;4484.730916145042;4492.146492166543;4595.310703394451;4611.331356953223;11433.485493152828;11700.122490813223;11702.2978621283;11702.88297117035;11703.356315402734;11118.166137539716;11420.714482137846;11517.712057198638;11615.186936914504;11629.53481860733;8803.647854085764;8882.111788821196;8884.644614215358;8914.153155450222;8942.239909607757;6489.837754056186;6766.221884483348;6791.864162716619;6884.139482564679;6892;10420.11429028541;10770.676879693621;10798.136149107895;10823.945880270549;10946.562137514411;10855.620053298097;10867.967543083394;11202.551897923991;11222.102231821598;11259.549618015188;7788.260115289038;7985.5208687478935;8037.718679846177;8990.775230619287;9190.40262382943;8330.416870818985;8498.660053598034;8713.477191368538;8744;8980.506072165832;8082.393820463606;8174.877376839233;8201.30288925424;8462.492250464991;8553.74338769838;8057.355411567178;8158.347332213528;8205.302549283379;8233.917660823554;8313.08556058821;7207.171175314906;7239.746608868664;7243.927560724325;7255.2019277878335;7287;5627.5497960162265;5636.210930469655;5713.057345538106;5746.741396637078;6025.8192254232445;5622.156676498558;5665.773369065191;5704.429557044428;5705.572082093777;5798.304203495608 03:51:34 INFO - PROCESS | 26781 | |4;prototype.html;359.5667220998696;360.1302853608536;362.94555816627485;363.3858778664059;367.93561126802814;5389.865253368663;5420.91868621971;5442;5479.753411096495;5489.533389661883;5230.947690523094;5375.43557926418;5411.540019098381;5413.648112872661;5419.783208671658;4213.85251516197;4266.530681625034;4276.550962148976;4288.785560721952;4301.3547967804925;4257.723247988886;4291.463567054103;4293.141371725652;4299.892502687427;4310.267254566716;4230.957690423103;4233;4248.936265956013;4252.596003379674;4290.841472802357;4066.4713587233764;4194.014406614443;4206.621404073648;4213.91572168558;4220.451341325624;3711.888643340704;3721.813909304524;3734.6265373462707;3741.30785804627;3759.6804271636943;5232.6598771080135;5235.528802407802;5239.423663397023;5248.580113590941;5293.179557168645;4770.880727981794;4856.344393506866;4872.610191184696;4885.853424397274;4894.143524883167;5616.943830561724;5669.971650141764;5671.7731290748325;5671.943280567224;5685.8009969651275;4108.130800485772;4138.8551400700835;4142.440770495975;4155.065110350147;4190.832366705328;5631.239782629334;5650.463205995424;5713.714314284269;5928.644281343133;5968.880622387572;4154.875353739392;4159.812808423628;4163.521195062546;4193.643540299047;4198.433211516467;4252.723572967747;4262.7016108872085;4262.957370426256;4264.211120942636;4269.786510674454;4665.2302370108555;4683.297505374221;4782.521747825259;4802.231642937183;4847.394075740533;5659.236003139606;5691.146328050742;5705.5435565154685;5747.224124743189;5778.104393818965;1463.9707205855823;1471.3378979459287;1471.8078356531244;1491.6345495353708;1511.8790496760232;926.5228407370209;935.6631612619451;937.3906960475609;939.9201067909304;944.8913374961967;4761.333413322138;4836.492168322346;4891.5353041461;4910.631702622246;4956.851294461172;5111.974440127776;5172.267290457738;5211.723127833629;5230.790768369298;5237.397699264558;4772.3557319762085;4887.511248875155;4910.386201724785;4919.0899683558655;4950.282209079735;2587.702414222351;2770.7921905857143;2772.80590358677;2790.623265859083;2820.464111818748;5148.382194136652;5204.713740744295;5225.824189557396;5251.6061295403;5272.657277277041;5109.284700141983;5257.447967963385;5265.289185959923;5271.6309858310315;5293.655912365684;4957.851264462072;5149.742512874342;5224.56324510755;5252.501012403815;5275.841724748264;5149.2276158576515;5186.559142472932;5223.2948551945;5227.424983251915;5237.16205407133;5158.690478571298;5175.430702622783;5531.363893152178;5647.971760141173;5679.949209396275;5309.33633295838;5755.165501002415;5870.442307980735;5872.148538461985;5884.205632239764;4164.958350416457;4165.687573431944;4166.500019997619;4181.83272669102;4187.958120418757;5759;5851.970740146442;5856.067497725655;5865.178874957424;5872.207252020922;5419.810306639249;5724.599278050663;5740.454656807597;5783.479486846204;5792.4497172768515;5661.490465857928;5715.31416230055;5753.1370294456165;5772.624779389326;5836.533077353716;5546.75039623226;5586.273784408001;5612.466815652507;5705.914411283917;5763.221965034667;5804.2744656917885;5807.7059561473825;5816.563757718103;5822.417758224313;5837.708114594168;5665.603407761582;5692.715364231689;5722.399148089391;5730.426957304237;5730.627509211888 03:51:34 INFO - PROCESS | 26781 | |5;yui.html;799.0101814170499;835.385991296397;847.6228078505068;861.487414988082;869.02669010708;14763.852361476382;15888.126153061577;15913.363465461382;15928.681426371495;15946.521604351874;16375.59061023475;16416.507504774847;16441.26014329355;16561.337546498155;16579.585510362245;733.1861633586715;838.3083955736512;861.3539845116147;866.7139843851514;875.8248350329959;883.0904168706243;885.7652722028668;888.0409158109227;889.3241136736095;889.5196593839352;922.4649703172178;922.6263363337858;923.1507013547518;924.1405492891641;924.4730503612943;755.1806290175167;884.5533005832013;904.2630256341126;909.0364213933186;920.7329874336434;668.5754545863363;669.1735706402588;669.5480550628306;669.6718607882151;669.7454967112466;15735.055896646123;15739.68520629581;15797.289121989537;15798.526044218692;15815.367385304575;14463.927680361636;14814.925925370411;15032.624184395369;15088.24558772057;15216.08703477795;13783.862161378358;14093.154410735286;14146.894002623074;14187.43250269988;14478.276086195649;4605.608523275526;4687.437507499088;4751.3348131261655;4758.881027974294;4775.594074503637;79280.20719792786;81602;81748.59125704333;84668.57665711694;85361;12560.120791544505;12718.300493472947;12760.6809829755;12825.294608796607;12964.870351296555;23342.533149337258;23483.41291467727;23488.88255558711;23874.164404245766;23896.522069558858;8413.579321033923;8440.915590844135;8698.21716045559;8716.08481109487;8963.641454341754;4928.704277743347;4953.380827396575;4968.503149685002;4991.600671946236;5043.52086551777;3435.2442462658173;3522.330757156132;3524.3656141894703;3532.5054492371087;3546.414841551167;636.748484348689;650.7917466410703;655.8653529394174;660.814971807885;670.9932900670931;723.7286017743346;725.7568714480708;726.393461459687;741.3698356397127;748.8464864702723;9814.46020468881;9991.600335986479;10151.187904967584;10325.793484130276;10370.585176592851;620.4478014567039;627.4948666323562;668.1514476614672;706.8091615263953;764.1785081037907;4948.356713627206;5134.178531434953;5138.177891537336;5155.355580552431;5193.37679478465;111106;111670;111851;114209.42895285471;120877.39561302314;95458;98915.50542247243;99547;103037.48481257545;105703;70799.29200707928;92019.07980920239;93361.5331923336;93803.18590442161;93852.53073734588;8812.383133180616;8824.691135810215;8861.069587693199;8875.112488751074;8993.415427997159;52779.73610131925;53948;53951;55925.44074559202;56615.86768264694;69939;80941;82025.17974820176;83682;85536.14463855281;11466.656000320003;11846.585369512026;12164.75670486603;12252.632421027027;12638.431270592662;10933.61732339396;12532.49870005226;12933.482660693468;13520.526781562598;14027;14269.500567480089;14357.56927292183;14414.711705766036;14546.854531454974;15053.172075535951;12340.568080117511;12899.484020639446;13224.669383265495;14800.925995369955;15681.451149209723;11309.264897781617;11615.128865335302;11771;11811.881881181422;11843.703907402383;11272.154588405738;11359.886401135884;11408.543658253577;11600.535978560763;11673.941630291794;11655.708607284885;11699.122565807429;11793.469293881968;11928.463219154984;12047.216930899463 03:51:34 INFO - PROCESS | 26781 | __end_tp_report 03:51:34 INFO - PROCESS | 26781 | __start_cc_report 03:51:34 INFO - PROCESS | 26781 | _x_x_mozilla_cycle_collect,899 03:51:34 INFO - PROCESS | 26781 | __end_cc_report 03:51:34 INFO - PROCESS | 26781 | __startTimestamp1468666294615__endTimestamp 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | ------- Summary: start ------- 03:51:34 INFO - PROCESS | 26781 | Number of tests: 6 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | [#0] dojo.html Cycles:165 Average:5404.67 Median:5551.17 stddev:583.80 (10.5%) stddev-sans-first:560.85 03:51:34 INFO - PROCESS | 26781 | Values: 3261.1 3296.4 3311.3 3322.3 3351.7 5377.4 5449.0 5466.6 5468.9 5487.7 5429.6 5455.6 5508.4 5510.8 5535.3 4828.8 4936.6 4992.4 5039.4 5098.2 4789.2 4924.5 4951.0 4966.8 4972.8 4904.4 4938.8 4958.0 4960.1 4963.0 5046.9 5052.9 5055.4 5063.3 5087.4 4841.1 4902.2 4936.1 4986.5 4990.3 5275.7 5292.0 5293.5 5307.8 5335.6 5804.6 5813.1 5838.3 5845.0 5847.6 5735.5 5811.0 5813.4 5886.9 5888.9 5473.9 5489.6 5514.9 5516.9 5525.1 5801.1 5995.5 5996.4 5996.9 6007.5 5686.4 5729.8 5740.5 5809.6 5829.7 5817.3 5820.9 5825.0 5905.9 5915.4 5551.2 5660.9 5783.5 5790.2 5795.3 5770.7 5850.0 5853.1 5861.1 5890.0 4499.5 4507.9 4509.2 4510.8 4534.1 3903.9 3930.2 3968.6 4035.9 4073.1 5422.2 5449.6 5456.9 5462.9 5480.1 5790.8 5803.2 5803.3 5805.0 5835.8 5266.8 5438.7 5440.7 5444.0 5469.0 5101.6 5101.9 5103.9 5104.2 5115.7 5971.9 5987.0 5987.4 5994.0 5997.3 5689.9 5707.1 5822.9 5831.0 5943.1 5932.7 5933.9 5938.5 5946.4 5950.8 5724.2 5760.1 5760.5 5767.9 5772.8 5682.2 5768.2 5800.4 5831.2 5883.9 5723.9 5726.7 5730.9 5731.3 5735.6 5496.2 5712.9 5731.8 5733.7 5734.0 5455.1 5486.1 5497.2 5553.3 5555.9 5664.8 5665.5 5686.1 5695.9 5705.4 5433.3 5470.5 5545.4 5558.0 5575.5 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | [#1] ext.html Cycles:180 Average:67744.99 Median:40240.90 stddev:140557.95 (349.3%) stddev-sans-first:140861.88 03:51:34 INFO - PROCESS | 26781 | Values: 613.4 645.1 657.0 665.1 673.7 33939.7 46859.0 48085.5 48197.5 48220.0 49328.8 49746.8 49786.3 49815.0 49946.0 8389.6 8973.5 9119.9 9130.5 10765.9 10448.1 10842.4 10845.7 10845.8 10848.7 10885.2 10935.3 10935.9 10936.1 10937.0 8430.6 8622.8 10822.2 10852.6 11122.8 10539.7 10990.0 11002.1 11006.0 11007.1 46880.0 47566.0 47592.0 47597.0 47633.5 3717.4 3879.2 4329.0 4367.1 4369.4 36353.1 36752.0 36887.3 37355.8 37779.0 4629.0 4635.8 4643.8 4667.4 4698.5 429327.0 434216.0 434225.0 434431.0 434641.0 39797.4 40851.6 40870.0 72363.3 73481.0 39897.6 40146.4 40228.0 40253.8 40284.2 17686.8 17871.1 17880.0 17881.4 17884.8 12541.0 13449.7 13561.4 13586.0 13794.6 9561.4 9590.3 9591.8 9616.9 9688.7 3578.2 3609.7 3662.7 3705.4 3707.2 1054.1 1096.0 1128.3 1149.7 1182.9 35241.3 35291.5 35422.8 35453.5 35568.0 1226.9 1245.4 1295.9 1298.6 1368.3 18289.6 18568.4 18696.2 18725.3 18753.4 640576.0 644736.0 646935.0 649219.0 650620.0 65721.0 66392.7 66418.0 66442.7 66489.0 1318.1 1434.9 1442.1 1456.0 1477.8 34616.0 35367.1 35687.6 35696.3 35732.5 53166.0 53264.7 53496.7 53745.2 53789.0 477906.0 478979.0 480142.0 480866.0 482357.0 28215.7 32716.3 33583.8 33670.3 33999.0 51874.0 52692.5 52985.7 53118.5 53878.0 43493.0 44027.6 44529.0 44732.3 45327.8 43047.0 43579.8 43954.3 44223.3 44444.0 44264.6 44823.8 45652.5 45739.0 45745.5 37551.6 39330.6 39419.8 39594.6 39858.4 28848.1 29207.7 29325.9 29351.9 29538.0 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | [#2] jquery.html Cycles:180 Average:31619.41 Median:11440.60 stddev:91403.22 (798.9%) stddev-sans-first:91629.64 03:51:34 INFO - PROCESS | 26781 | Values: 432.5 432.9 434.6 438.0 445.5 18782.0 19918.9 20704.1 20733.3 20788.0 19704.6 20266.0 20295.4 20305.4 20510.6 5703.8 5879.9 5933.5 5944.3 5960.1 5700.6 5852.9 5863.8 5866.9 5884.2 5802.4 5803.7 5804.2 5805.8 5815.5 5739.5 5832.0 5834.4 5837.5 5840.9 5009.3 5092.4 5219.9 5246.6 5250.1 20527.0 21967.9 23331.5 23699.0 24026.2 2643.5 2704.2 2755.2 2823.4 3071.8 9814.7 10013.6 10230.3 11130.7 15492.7 1929.3 1978.5 2003.9 2100.1 2110.2 84337.0 87637.0 90218.5 91123.0 92132.5 11105.7 11342.4 11607.7 11683.4 12021.2 14697.6 14869.4 14942.6 15083.8 15554.8 8528.1 8590.6 8754.5 8812.8 8839.3 8771.7 8774.5 8796.9 8924.4 8947.9 4452.7 4638.7 4684.3 4691.5 4860.2 1797.5 1802.7 1953.9 2093.2 2108.5 952.3 1036.0 1045.4 1089.9 1095.6 19415.3 19520.9 19557.4 19743.2 19901.8 1050.3 1068.9 1095.7 1100.8 1119.9 10740.4 11233.4 11297.8 11306.5 11344.5 541659.0 544593.0 557842.0 558775.0 568772.0 99272.0 101283.5 101679.5 102437.0 103288.5 1021.8 1029.6 1030.6 1042.0 1073.7 17149.5 17307.7 17475.0 17574.8 17728.7 30767.4 37927.2 42612.0 43094.8 44221.3 60016.4 61182.4 62569.4 63432.7 64432.0 9038.1 9081.5 9106.4 9217.2 9375.7 10441.4 13223.3 13662.3 13733.4 13914.6 11796.1 11816.6 12002.3 12043.2 12082.9 11221.7 11306.8 11442.3 11696.5 11975.6 11692.9 11935.5 12075.8 12110.1 12479.0 9064.1 9162.6 9166.9 12093.7 12666.5 12087.8 12540.3 12697.7 13104.9 13181.3 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | [#3] mootools.html Cycles:180 Average:6178.87 Median:4488.44 stddev:3499.42 (78.0%) stddev-sans-first:3490.72 03:51:34 INFO - PROCESS | 26781 | Values: 1388.9 1570.5 1597.8 1795.3 1917.9 7840.0 9107.0 9393.3 9407.3 9686.1 9316.2 9498.6 9504.0 9515.6 9517.0 2145.6 2373.7 2462.4 2467.5 2500.8 2490.3 2514.4 2515.0 2517.5 2517.7 2389.1 2433.3 2436.0 2436.8 2439.7 2060.5 2111.3 2282.1 2371.7 2374.9 2399.6 2427.5 2429.0 2429.1 2429.5 9422.3 9456.9 9457.4 9465.8 9467.6 5287.0 5308.0 5396.8 5421.2 5431.9 3436.3 3524.6 3547.6 3566.3 3609.4 1259.8 1271.8 1272.5 1291.5 1304.5 12920.7 12931.1 12941.5 12964.3 12972.1 4771.9 4786.7 4894.5 4901.8 4902.0 10651.0 10718.9 10721.2 10724.0 10742.2 5580.5 5583.4 5586.9 5590.9 5591.7 4444.5 4890.5 5027.5 5031.6 5101.8 4138.0 4188.4 4193.4 4210.9 4226.3 1316.6 1360.4 1362.3 1365.7 1374.0 978.2 988.4 1044.9 1096.2 1105.9 6369.0 6467.9 6511.4 6574.6 6760.8 1088.5 1127.1 1128.5 1161.5 1164.4 4383.8 4484.7 4492.1 4595.3 4611.3 11433.5 11700.1 11702.3 11702.9 11703.4 11118.2 11420.7 11517.7 11615.2 11629.5 8803.6 8882.1 8884.6 8914.2 8942.2 6489.8 6766.2 6791.9 6884.1 6892.0 10420.1 10770.7 10798.1 10823.9 10946.6 10855.6 10868.0 11202.6 11222.1 11259.5 7788.3 7985.5 8037.7 8990.8 9190.4 8330.4 8498.7 8713.5 8744.0 8980.5 8082.4 8174.9 8201.3 8462.5 8553.7 8057.4 8158.3 8205.3 8233.9 8313.1 7207.2 7239.7 7243.9 7255.2 7287.0 5627.5 5636.2 5713.1 5746.7 6025.8 5622.2 5665.8 5704.4 5705.6 5798.3 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | [#4] prototype.html Cycles:180 Average:4646.20 Median:5208.22 stddev:1337.26 (25.7%) stddev-sans-first:1301.73 03:51:34 INFO - PROCESS | 26781 | Values: 359.6 360.1 362.9 363.4 367.9 5389.9 5420.9 5442.0 5479.8 5489.5 5230.9 5375.4 5411.5 5413.6 5419.8 4213.9 4266.5 4276.6 4288.8 4301.4 4257.7 4291.5 4293.1 4299.9 4310.3 4231.0 4233.0 4248.9 4252.6 4290.8 4066.5 4194.0 4206.6 4213.9 4220.5 3711.9 3721.8 3734.6 3741.3 3759.7 5232.7 5235.5 5239.4 5248.6 5293.2 4770.9 4856.3 4872.6 4885.9 4894.1 5616.9 5670.0 5671.8 5671.9 5685.8 4108.1 4138.9 4142.4 4155.1 4190.8 5631.2 5650.5 5713.7 5928.6 5968.9 4154.9 4159.8 4163.5 4193.6 4198.4 4252.7 4262.7 4263.0 4264.2 4269.8 4665.2 4683.3 4782.5 4802.2 4847.4 5659.2 5691.1 5705.5 5747.2 5778.1 1464.0 1471.3 1471.8 1491.6 1511.9 926.5 935.7 937.4 939.9 944.9 4761.3 4836.5 4891.5 4910.6 4956.9 5112.0 5172.3 5211.7 5230.8 5237.4 4772.4 4887.5 4910.4 4919.1 4950.3 2587.7 2770.8 2772.8 2790.6 2820.5 5148.4 5204.7 5225.8 5251.6 5272.7 5109.3 5257.4 5265.3 5271.6 5293.7 4957.9 5149.7 5224.6 5252.5 5275.8 5149.2 5186.6 5223.3 5227.4 5237.2 5158.7 5175.4 5531.4 5648.0 5679.9 5309.3 5755.2 5870.4 5872.1 5884.2 4165.0 4165.7 4166.5 4181.8 4188.0 5759.0 5852.0 5856.1 5865.2 5872.2 5419.8 5724.6 5740.5 5783.5 5792.4 5661.5 5715.3 5753.1 5772.6 5836.5 5546.8 5586.3 5612.5 5705.9 5763.2 5804.3 5807.7 5816.6 5822.4 5837.7 5665.6 5692.7 5722.4 5730.4 5730.6 03:51:34 INFO - PROCESS | 26781 | 03:51:34 INFO - PROCESS | 26781 | [#5] yui.html Cycles:180 Average:21539.82 Median:4938.53 stddev:30842.18 (624.5%) stddev-sans-first:30889.39 03:51:34 INFO - PROCESS | 26781 | Values: 799.0 835.4 847.6 861.5 869.0 14763.9 15888.1 15913.4 15928.7 15946.5 16375.6 16416.5 16441.3 16561.3 16579.6 733.2 838.3 861.4 866.7 875.8 883.1 885.8 888.0 889.3 889.5 922.5 922.6 923.2 924.1 924.5 755.2 884.6 904.3 909.0 920.7 668.6 669.2 669.5 669.7 669.7 15735.1 15739.7 15797.3 15798.5 15815.4 14463.9 14814.9 15032.6 15088.2 15216.1 13783.9 14093.2 14146.9 14187.4 14478.3 4605.6 4687.4 4751.3 4758.9 4775.6 79280.2 81602.0 81748.6 84668.6 85361.0 12560.1 12718.3 12760.7 12825.3 12964.9 23342.5 23483.4 23488.9 23874.2 23896.5 8413.6 8440.9 8698.2 8716.1 8963.6 4928.7 4953.4 4968.5 4991.6 5043.5 3435.2 3522.3 3524.4 3532.5 3546.4 636.7 650.8 655.9 660.8 671.0 723.7 725.8 726.4 741.4 748.8 9814.5 9991.6 10151.2 10325.8 10370.6 620.4 627.5 668.2 706.8 764.2 4948.4 5134.2 5138.2 5155.4 5193.4 111106.0 111670.0 111851.0 114209.4 120877.4 95458.0 98915.5 99547.0 103037.5 105703.0 70799.3 92019.1 93361.5 93803.2 93852.5 8812.4 8824.7 8861.1 8875.1 8993.4 52779.7 53948.0 53951.0 55925.4 56615.9 69939.0 80941.0 82025.2 83682.0 85536.1 11466.7 11846.6 12164.8 12252.6 12638.4 10933.6 12532.5 12933.5 13520.5 14027.0 14269.5 14357.6 14414.7 14546.9 15053.2 12340.6 12899.5 13224.7 14800.9 15681.5 11309.3 11615.1 11771.0 11811.9 11843.7 11272.2 11359.9 11408.5 11600.5 11673.9 11655.7 11699.1 11793.5 11928.5 12047.2 03:51:34 INFO - PROCESS | 26781 | -------- Summary: end -------- 03:51:34 INFO - PROCESS | 26781 | 03:51:35 INFO - TEST-INFO | 26781: exit 0 03:51:35 INFO - TEST-OK | dromaeo_css | took 1112738ms 03:51:35 INFO - TEST-START | kraken 03:51:35 INFO - operating with platform_type : linux_ 03:51:35 INFO - Initialising browser for kraken test... 03:51:35 INFO - TEST-INFO | started process 26985 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKDeQnX/profile http://localhost:43233/getInfo.html) 03:51:39 INFO - PROCESS | 26985 | __metrics Screen width/height:1600/1200 03:51:39 INFO - PROCESS | 26985 | colorDepth:24 03:51:39 INFO - PROCESS | 26985 | Browser inner width/height: 1024/697 03:51:39 INFO - PROCESS | 26985 | __metrics 03:51:41 INFO - PROCESS | 26985 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:51:41 ERROR - PROCESS | 26985 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:51:42 INFO - TEST-INFO | 26985: exit 0 03:51:42 INFO - Browser initialized. 03:51:42 INFO - Running cycle 1/1 for kraken test... 03:51:42 INFO - TEST-INFO | started process 27081 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKDeQnX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:51:54 INFO - PROCESS | 27081 | RSS: Main: 145920000 03:51:54 INFO - PROCESS | 27081 | 03:51:56 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43233/tests/kraken/driver.html?testName=audio-beat-detection) 03:51:57 INFO - PROCESS | 27081 | RSS: Main: 195399680 03:51:57 INFO - PROCESS | 27081 | 03:51:59 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43233/tests/kraken/driver.html?testName=audio-dft) 03:52:00 INFO - PROCESS | 27081 | RSS: Main: 192925696 03:52:00 INFO - PROCESS | 27081 | 03:52:03 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43233/tests/kraken/driver.html?testName=audio-fft) 03:52:03 INFO - PROCESS | 27081 | RSS: Main: 279048192 03:52:03 INFO - PROCESS | 27081 | 03:52:06 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43233/tests/kraken/driver.html?testName=audio-oscillator) 03:52:06 INFO - PROCESS | 27081 | RSS: Main: 187322368 03:52:06 INFO - PROCESS | 27081 | 03:52:08 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:52:09 INFO - PROCESS | 27081 | RSS: Main: 188706816 03:52:09 INFO - PROCESS | 27081 | 03:52:12 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43233/tests/kraken/driver.html?testName=imaging-darkroom) 03:52:12 INFO - PROCESS | 27081 | RSS: Main: 222834688 03:52:12 INFO - PROCESS | 27081 | 03:52:16 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43233/tests/kraken/driver.html?testName=imaging-desaturate) 03:52:16 INFO - PROCESS | 27081 | RSS: Main: 223469568 03:52:16 INFO - PROCESS | 27081 | 03:52:19 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43233/tests/kraken/driver.html?testName=json-parse-financial) 03:52:20 INFO - PROCESS | 27081 | RSS: Main: 223297536 03:52:20 INFO - PROCESS | 27081 | 03:52:21 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:52:22 INFO - PROCESS | 27081 | RSS: Main: 201351168 03:52:22 INFO - PROCESS | 27081 | 03:52:23 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:52:24 INFO - PROCESS | 27081 | RSS: Main: 202502144 03:52:24 INFO - PROCESS | 27081 | 03:52:26 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:52:27 INFO - PROCESS | 27081 | RSS: Main: 207306752 03:52:27 INFO - PROCESS | 27081 | 03:52:29 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:52:30 INFO - PROCESS | 27081 | RSS: Main: 216064000 03:52:30 INFO - PROCESS | 27081 | 03:52:32 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:52:33 INFO - PROCESS | 27081 | RSS: Main: 217702400 03:52:33 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | Cycle 1(1): loaded http://localhost:43233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43233/tests/kraken/driver.html?testName=ai-astar) 03:52:35 INFO - PROCESS | 27081 | RSS: Main: 260091904 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | __start_tp_report 03:52:35 INFO - PROCESS | 27081 | _x_x_mozilla_page_load 03:52:35 INFO - PROCESS | 27081 | _x_x_mozilla_page_load_details 03:52:35 INFO - PROCESS | 27081 | |i|pagename|runs| 03:52:35 INFO - PROCESS | 27081 | |0;ai-astar;111;103;111;108;110;109;111;110;109;110 03:52:35 INFO - PROCESS | 27081 | |1;audio-beat-detection;151;122;131;169;123;125;160;124;153;126 03:52:35 INFO - PROCESS | 27081 | |2;audio-dft;161;161;161;161;161;160;162;160;161;159 03:52:35 INFO - PROCESS | 27081 | |3;audio-fft;74;75;75;126;76;78;76;74;97;75 03:52:35 INFO - PROCESS | 27081 | |4;audio-oscillator;84;83;107;84;84;85;112;85;84;123 03:52:35 INFO - PROCESS | 27081 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;116;116;116 03:52:35 INFO - PROCESS | 27081 | |6;imaging-darkroom;146;146;145;145;146;145;145;145;145;145 03:52:35 INFO - PROCESS | 27081 | |7;imaging-desaturate;94;94;95;94;94;95;96;95;95;95 03:52:35 INFO - PROCESS | 27081 | |8;json-parse-financial;50;53;49;52;53;50;51;50;51;51 03:52:35 INFO - PROCESS | 27081 | |9;json-stringify-tinderbox;59;61;60;59;59;60;59;60;59;59 03:52:35 INFO - PROCESS | 27081 | |10;stanford-crypto-aes;80;79;76;75;76;77;77;76;117;76 03:52:35 INFO - PROCESS | 27081 | |11;stanford-crypto-ccm;129;119;128;125;116;115;117;123;141;123 03:52:35 INFO - PROCESS | 27081 | |12;stanford-crypto-pbkdf2;155;152;151;153;150;153;151;150;149;149 03:52:35 INFO - PROCESS | 27081 | |13;stanford-crypto-sha256-iterative;61;60;64;61;63;60;61;61;62;59 03:52:35 INFO - PROCESS | 27081 | __end_tp_report 03:52:35 INFO - PROCESS | 27081 | __start_cc_report 03:52:35 INFO - PROCESS | 27081 | _x_x_mozilla_cycle_collect,1590 03:52:35 INFO - PROCESS | 27081 | __end_cc_report 03:52:35 INFO - PROCESS | 27081 | __startTimestamp1468666355446__endTimestamp 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | ------- Summary: start ------- 03:52:35 INFO - PROCESS | 27081 | Number of tests: 14 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:2.39 (2.2%) stddev-sans-first:2.45 03:52:35 INFO - PROCESS | 27081 | Values: 111.0 103.0 111.0 108.0 110.0 109.0 111.0 110.0 109.0 110.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:141.00 stddev:17.88 (12.7%) stddev-sans-first:18.37 03:52:35 INFO - PROCESS | 27081 | Values: 151.0 122.0 131.0 169.0 123.0 125.0 160.0 124.0 153.0 126.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 03:52:35 INFO - PROCESS | 27081 | Values: 161.0 161.0 161.0 161.0 161.0 160.0 162.0 160.0 161.0 159.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.50 stddev:16.73 (22.2%) stddev-sans-first:17.46 03:52:35 INFO - PROCESS | 27081 | Values: 74.0 75.0 75.0 126.0 76.0 78.0 76.0 74.0 97.0 75.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:84.00 stddev:14.94 (17.8%) stddev-sans-first:15.48 03:52:35 INFO - PROCESS | 27081 | Values: 84.0 83.0 107.0 84.0 84.0 85.0 112.0 85.0 84.0 123.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:52:35 INFO - PROCESS | 27081 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 03:52:35 INFO - PROCESS | 27081 | Values: 146.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 03:52:35 INFO - PROCESS | 27081 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 96.0 95.0 95.0 95.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.33 (2.6%) stddev-sans-first:1.36 03:52:35 INFO - PROCESS | 27081 | Values: 50.0 53.0 49.0 52.0 53.0 50.0 51.0 50.0 51.0 51.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 03:52:35 INFO - PROCESS | 27081 | Values: 59.0 61.0 60.0 59.0 59.0 60.0 59.0 60.0 59.0 59.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:76.50 stddev:12.78 (16.7%) stddev-sans-first:13.55 03:52:35 INFO - PROCESS | 27081 | Values: 80.0 79.0 76.0 75.0 76.0 77.0 77.0 76.0 117.0 76.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:124.00 stddev:7.82 (6.3%) stddev-sans-first:8.05 03:52:35 INFO - PROCESS | 27081 | Values: 129.0 119.0 128.0 125.0 116.0 115.0 117.0 123.0 141.0 123.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.50 stddev:1.95 (1.3%) stddev-sans-first:1.54 03:52:35 INFO - PROCESS | 27081 | Values: 155.0 152.0 151.0 153.0 150.0 153.0 151.0 150.0 149.0 149.0 03:52:35 INFO - PROCESS | 27081 | 03:52:35 INFO - PROCESS | 27081 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.48 (2.4%) stddev-sans-first:1.56 03:52:35 INFO - PROCESS | 27081 | Values: 61.0 60.0 64.0 61.0 63.0 60.0 61.0 61.0 62.0 59.0 03:52:35 INFO - PROCESS | 27081 | -------- Summary: end -------- 03:52:35 INFO - PROCESS | 27081 | 03:52:36 INFO - TEST-INFO | 27081: exit 0 03:52:36 INFO - TEST-OK | kraken | took 61137ms 03:52:36 INFO - SUITE-END | took 1173s 03:52:36 INFO - Completed test suite (00:19:33) 03:52:36 INFO - javascript benchmark 03:52:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3261.070594880459, 3296.4066468035758, 3311.337732453509, 3322.3355328934217, 3351.681590248927, 5377.408485066644, 5449.0, 5466.562674986001, 5468.945310546894, 5487.670739755613, 5429.592780541456, 5455.618106732531, 5508.394076651575, 5510.834674959748, 5535.308086489189, 4828.7585620719, 4936.629752768547, 4992.425871024842, 5039.44566097729, 5098.209777484496, 4789.20978038625, 4924.4583095859425, 4951.0, 4966.801327946896, 4972.776225069862, 4904.362432883738, 4938.80244790208, 4957.975210123962, 4960.057589058086, 4963.032208719301, 5046.924296135561, 5052.949470505285, 5055.418626857903, 5063.3417655704525, 5087.440381558025, 4841.128596852565, 4902.240152776325, 4936.086823937547, 4986.476419975923, 4990.276409920578, 5275.709835959021, 5292.047431462335, 5293.470652934714, 5307.814226502092, 5335.599830012726, 5804.564657650693, 5813.069908814612, 5838.299404071496, 5845.035569131088, 5847.619904706223, 5735.455131762517, 5811.0, 5813.360530341659, 5886.911696324559, 5888.852778680567, 5473.890522189574, 5489.560835133179, 5514.862128446741, 5516.889662206733, 5525.143602741622, 5801.071828507418, 5995.46040856325, 5996.430339117776, 5996.910046349309, 6007.459328660354, 5686.43135686428, 5729.828105156852, 5740.54075673945, 5809.622374545641, 5829.650220986754, 5817.301923769175, 5820.941790582125, 5825.0, 5905.852353691106, 5915.438033386821, 5551.167324901297, 5660.943390566124, 5783.450572195718, 5790.218320526759, 5795.304563452329, 5770.711464426762, 5850.034744267234, 5853.0635098384055, 5861.062230043172, 5889.970550147307, 4499.505054444008, 4507.940633951019, 4509.1658043262105, 4510.819567217274, 4534.070515544306, 3903.921921561553, 3930.2336044471626, 3968.6229808168177, 4035.858744943913, 4073.144639625712, 5422.240886275925, 5449.64577302482, 5456.91814622781, 5462.863428414242, 5480.095784195642, 5790.797322093791, 5803.245578074823, 5803.274590676166, 5805.0, 5835.79574714883, 5266.78932842682, 5438.700871452108, 5440.727963601804, 5443.972780136074, 5469.042917489503, 5101.566366858859, 5101.923471147936, 5103.923441148386, 5104.157813960729, 5115.693058416433, 5971.880562388728, 5986.970065149733, 5987.371326010793, 5994.0, 5997.280326360865, 5689.914651280152, 5707.143928410772, 5822.883542329045, 5831.0378787499585, 5943.138244954457, 5932.7033648318275, 5933.940660593339, 5938.465538101592, 5946.375630558729, 5950.821475355573, 5724.169995350734, 5760.135979603093, 5760.510356619567, 5767.8558036049435, 5772.826815195551, 5682.176084467646, 5768.221290125947, 5800.39095894925, 5831.1544826000845, 5883.94116058834, 5723.8569035774435, 5726.685032323096, 5730.856728581819, 5731.254936858349, 5735.569832262514, 5496.203050557567, 5712.857178570569, 5731.770729170953, 5733.684647344436, 5733.971330143323, 5455.072637651688, 5486.149646804752, 5497.230387745799, 5553.278073850535, 5555.8611034722835, 5664.801731939362, 5665.518430933252, 5686.147077938343, 5695.943040569541, 5705.372409035086, 5433.266509021231, 5470.452954704498, 5545.417731138334, 5557.972210138923, 5575.470330318613], "value": 5367.872824823576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.3590398034054, 645.1161908185785, 657.0472814419094, 665.1386454541365, 673.7237732529662, 33939.66060339396, 46859.0, 48085.51914480854, 48197.51802481974, 48220.0, 49328.75335623317, 49746.75126624371, 49786.25320620194, 49815.0, 49946.0, 8389.622466989, 8973.46159230445, 9119.860017497813, 9130.452172869616, 10765.946170269137, 10448.111910487618, 10842.403667798268, 10845.728856778565, 10845.83731244028, 10848.67453976382, 10885.23803333767, 10935.28920620161, 10935.945320273388, 10936.12510999122, 10937.0, 8430.62062207199, 8622.784430389227, 10822.24244302899, 10852.565897364175, 11122.833157502644, 10539.683809485728, 10990.0, 11002.064824489928, 11006.0, 11007.06439952605, 46880.0, 47566.0, 47592.0, 47597.0, 47633.523664763605, 3717.423799311084, 3879.1853710720784, 4329.047609525899, 4367.10474352757, 4369.4101296325225, 36353.091172720626, 36752.0, 36887.26225475476, 37355.813220934, 37779.0, 4629.0, 4635.791389387486, 4643.767811609406, 4667.393238878924, 4698.506656801055, 429327.0, 434216.0, 434225.0, 434431.0, 434641.0, 39797.40303895444, 40851.59148408537, 40870.0, 72363.2763672367, 73481.0, 39897.60102398939, 40146.39780403296, 40228.0, 40253.79873100616, 40284.19431611351, 17686.823131768517, 17871.106444677713, 17880.0, 17881.37415190462, 17884.821151788317, 12541.0, 13449.663758406117, 13561.389737461837, 13586.0, 13794.586162415144, 9561.378510396802, 9590.328676992684, 9591.808163836684, 9616.9038309616, 9688.70933871985, 3578.248567800739, 3609.7473176877365, 3662.689100803792, 3705.4071348584635, 3707.2400157967554, 1054.1040115901421, 1095.9945200273949, 1128.3230061962756, 1149.735560821016, 1182.8876256755595, 35241.295174096886, 35291.4706279406, 35422.822885885405, 35453.468197977054, 35568.0, 1226.907981901361, 1245.3835351501066, 1295.8509771376223, 1298.642873209856, 1368.3021658954005, 18289.634207316045, 18568.44294671162, 18696.158672859754, 18725.25098996025, 18753.437396878115, 640576.0, 644736.0, 646935.0, 649219.0, 650620.0, 65721.0, 66392.66803665951, 66418.0, 66442.66778666172, 66489.0, 1318.1432069155126, 1434.8780353670054, 1442.113100443241, 1455.9854401456062, 1477.8152730908637, 34616.0, 35367.11582210465, 35687.64312356843, 35696.28607427785, 35732.46401304034, 53166.0, 53264.73367633292, 53496.73251633717, 53745.19382209348, 53789.0, 477906.0, 478979.0, 480142.0, 480866.0, 482357.0, 28215.71784282213, 32716.34567308593, 33583.83208083944, 33670.3265934675, 33999.0, 51874.0, 52692.47307527029, 52985.735071324394, 53118.46881531135, 53878.0, 43492.98476077245, 44027.559724402345, 44529.0, 44732.32901506545, 45327.77336113298, 43047.0, 43579.782101089295, 43954.34068489039, 44223.33664995092, 44444.0, 44264.55735442733, 44823.77588112038, 45652.543474566155, 45739.0, 45745.54254457413, 37551.62344262034, 39330.60669393269, 39419.80290098531, 39594.60405395909, 39858.40212396874, 28848.134555963356, 29207.707922920497, 29325.85337073301, 29351.85324073366, 29538.0], "value": 21030.896098490688, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.50046196642876, 432.8723026707121, 434.55829827764103, 438.0472472372589, 445.5188396531748, 18782.0, 19918.90040549797, 20704.068316925735, 20733.274335398262, 20788.0, 19704.60590788187, 20266.0, 20295.391138265877, 20305.390838274838, 20510.589788204263, 5703.828885133442, 5879.941200588003, 5933.495652869511, 5944.256967879015, 5960.105984102376, 5700.572457065716, 5852.853678658025, 5863.824085277427, 5866.911996320058, 5884.176215329857, 5802.448767367115, 5803.738831752581, 5804.245448091742, 5805.796797112102, 5815.47660710536, 5739.512141467981, 5832.0, 5834.445727655866, 5837.50381217597, 5840.941590584103, 5009.298698182258, 5092.414372347191, 5219.895602087975, 5246.606504512139, 5250.081235783722, 20527.0, 21967.890160549094, 23331.53336933252, 23699.0, 24026.159084432136, 2643.537380958344, 2704.2428120126397, 2755.2147637923094, 2823.378856651533, 3071.831049292288, 9814.705558833246, 10013.59945602175, 10230.335028223217, 11130.721731956684, 15492.690146197014, 1929.305450037985, 1978.5251539630528, 2003.8597298189134, 2100.1494394770143, 2110.1981247126146, 84337.0, 87637.0, 90218.5489072557, 91123.0, 92132.53933730288, 11105.666829995112, 11342.376169310603, 11607.6517704469, 11683.357415342069, 12021.158518903767, 14697.632559186106, 14869.405223791144, 14942.626434339229, 15083.773743393858, 15554.766678499831, 8528.147185281547, 8590.613422396007, 8754.47473151613, 8812.77968050791, 8839.29285657146, 8771.736847894574, 8774.508333583306, 8796.91203087961, 8924.375293729507, 8947.865782013276, 4452.666050046195, 4638.744869032235, 4684.250519916797, 4691.50739172382, 4860.222364421676, 1797.4653882954813, 1802.6574950759314, 1953.90230488475, 2093.1941202637095, 2108.4518025313223, 952.3476418653245, 1035.9533820978074, 1045.3936716704293, 1089.8910108989196, 1095.64939219449, 19415.3204637837, 19520.902395488214, 19557.413277601696, 19743.21027158926, 19901.800981989996, 1050.3015494696094, 1068.871735391758, 1095.687728997232, 1100.8293714474269, 1119.9328040317607, 10740.355578665305, 11233.438328083563, 11297.774044519228, 11306.491207895664, 11344.489497972447, 541659.0, 544593.0, 557842.0, 558775.0, 568772.0, 99272.0, 101283.49358253309, 101679.48219290086, 102437.0, 103288.48355758173, 1021.8467229915572, 1029.5727273181697, 1030.5826140413144, 1042.0413219837767, 1073.6778966309919, 17149.542288905584, 17307.653846922738, 17475.0, 17574.82425175783, 17728.734068989233, 30767.384652307275, 37927.24145517129, 42612.0, 43094.78452607717, 44221.336679949185, 60016.39983600108, 61182.38817611767, 62569.37430625818, 63432.68283658552, 64432.0, 9038.141376569216, 9081.500517471339, 9106.362554621113, 9217.170454659114, 9375.718728438158, 10441.373517588969, 13223.3388330585, 13662.316884155554, 13733.381997810322, 13914.582562523141, 11796.115291353011, 11816.645500634995, 12002.279863208236, 12043.217190882564, 12082.879171208528, 11221.663350099183, 11306.830397544209, 11442.313461192354, 11696.473658685209, 11975.64073077809, 11692.883071169179, 11935.462904169159, 12075.75848483043, 12110.091743119478, 12479.0, 9064.138906803842, 9162.633494660138, 9166.862497062682, 12093.697657558632, 12666.493340266286, 12087.75824483523, 12540.310282934526, 12697.746045078862, 13104.868951310365, 13181.275029873448], "value": 9653.074901758962, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.9166650000993, 1570.4895908829633, 1597.8322276161002, 1795.2639417838689, 1917.904104794759, 7839.960800195998, 9106.954465227673, 9393.342466027363, 9407.341486095977, 9686.07982241686, 9316.208122309596, 9498.620055197802, 9504.0, 9515.571799269032, 9517.04829517048, 2145.592337455886, 2373.6795532603137, 2462.384403899025, 2467.543504451682, 2500.7999360051244, 2490.2653717153394, 2514.421683012908, 2515.0317127905796, 2517.471331020479, 2517.748225177477, 2389.1279682915697, 2433.3065076453213, 2435.963460548084, 2436.7928726058217, 2439.73162952076, 2060.5054786851124, 2111.3032699209225, 2282.1213832674375, 2371.727251366089, 2374.8812559371963, 2399.6160614301803, 2427.490227052321, 2429.0, 2429.149797570854, 2429.5383877063477, 9422.293328000358, 9456.905430945671, 9457.3852699575, 9465.763355916088, 9467.573959171854, 5287.021900948298, 5307.9734601326745, 5396.811111611114, 5421.186821976696, 5431.8593095450005, 3436.347094052147, 3524.594671612772, 3547.592026916886, 3566.2689148724708, 3609.3683605368956, 1259.8488181418288, 1271.8155867399175, 1272.5291642092393, 1291.5027714330051, 1304.4912484131196, 12920.676983075404, 12931.094823362368, 12941.547045853444, 12964.286964216964, 12972.09195356316, 4771.908165906701, 4786.712797232177, 4894.510548945148, 4901.828436004723, 4901.950980490221, 10651.041406273473, 10718.89281107179, 10721.249512534203, 10724.03483686472, 10742.194335424874, 5580.469855363734, 5583.4137415571595, 5586.91619625706, 5590.86022849432, 5591.664500129924, 4444.533324000998, 4890.486498917634, 5027.497250275016, 5031.572316353151, 5101.795928162832, 4138.048248902769, 4188.392683060939, 4193.35003074524, 4210.85262015828, 4226.344916537942, 1316.5655333739842, 1360.3606305036617, 1362.257569624557, 1365.7473367427058, 1373.979390309126, 978.188103873783, 988.357567581078, 1044.9373037617768, 1096.1504833753904, 1105.8617672790901, 6369.0, 6467.870642587122, 6511.446527045159, 6574.572652777554, 6760.797176084725, 1088.4666513408372, 1127.1095834290818, 1128.503458478267, 1161.4599211366756, 1164.4294295795169, 4383.802728877208, 4484.730916145042, 4492.146492166543, 4595.310703394451, 4611.331356953223, 11433.485493152828, 11700.122490813223, 11702.2978621283, 11702.88297117035, 11703.356315402734, 11118.166137539716, 11420.714482137846, 11517.712057198638, 11615.186936914504, 11629.53481860733, 8803.647854085764, 8882.111788821196, 8884.644614215358, 8914.153155450222, 8942.239909607757, 6489.837754056186, 6766.221884483348, 6791.864162716619, 6884.139482564679, 6892.0, 10420.11429028541, 10770.676879693621, 10798.136149107895, 10823.945880270549, 10946.562137514411, 10855.620053298097, 10867.967543083394, 11202.551897923991, 11222.102231821598, 11259.549618015188, 7788.260115289038, 7985.5208687478935, 8037.718679846177, 8990.775230619287, 9190.40262382943, 8330.416870818985, 8498.660053598034, 8713.477191368538, 8744.0, 8980.506072165832, 8082.393820463606, 8174.877376839233, 8201.30288925424, 8462.492250464991, 8553.74338769838, 8057.355411567178, 8158.347332213528, 8205.302549283379, 8233.917660823554, 8313.08556058821, 7207.171175314906, 7239.746608868664, 7243.927560724325, 7255.2019277878335, 7287.0, 5627.5497960162265, 5636.210930469655, 5713.057345538106, 5746.741396637078, 6025.8192254232445, 5622.156676498558, 5665.773369065191, 5704.429557044428, 5705.572082093777, 5798.304203495608], "value": 4956.333381362311, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.5667220998696, 360.1302853608536, 362.94555816627485, 363.3858778664059, 367.93561126802814, 5389.865253368663, 5420.91868621971, 5442.0, 5479.753411096495, 5489.533389661883, 5230.947690523094, 5375.43557926418, 5411.540019098381, 5413.648112872661, 5419.783208671658, 4213.85251516197, 4266.530681625034, 4276.550962148976, 4288.785560721952, 4301.3547967804925, 4257.723247988886, 4291.463567054103, 4293.141371725652, 4299.892502687427, 4310.267254566716, 4230.957690423103, 4233.0, 4248.936265956013, 4252.596003379674, 4290.841472802357, 4066.4713587233764, 4194.014406614443, 4206.621404073648, 4213.91572168558, 4220.451341325624, 3711.888643340704, 3721.813909304524, 3734.6265373462707, 3741.30785804627, 3759.6804271636943, 5232.6598771080135, 5235.528802407802, 5239.423663397023, 5248.580113590941, 5293.179557168645, 4770.880727981794, 4856.344393506866, 4872.610191184696, 4885.853424397274, 4894.143524883167, 5616.943830561724, 5669.971650141764, 5671.7731290748325, 5671.943280567224, 5685.8009969651275, 4108.130800485772, 4138.8551400700835, 4142.440770495975, 4155.065110350147, 4190.832366705328, 5631.239782629334, 5650.463205995424, 5713.714314284269, 5928.644281343133, 5968.880622387572, 4154.875353739392, 4159.812808423628, 4163.521195062546, 4193.643540299047, 4198.433211516467, 4252.723572967747, 4262.7016108872085, 4262.957370426256, 4264.211120942636, 4269.786510674454, 4665.2302370108555, 4683.297505374221, 4782.521747825259, 4802.231642937183, 4847.394075740533, 5659.236003139606, 5691.146328050742, 5705.5435565154685, 5747.224124743189, 5778.104393818965, 1463.9707205855823, 1471.3378979459287, 1471.8078356531244, 1491.6345495353708, 1511.8790496760232, 926.5228407370209, 935.6631612619451, 937.3906960475609, 939.9201067909304, 944.8913374961967, 4761.333413322138, 4836.492168322346, 4891.5353041461, 4910.631702622246, 4956.851294461172, 5111.974440127776, 5172.267290457738, 5211.723127833629, 5230.790768369298, 5237.397699264558, 4772.3557319762085, 4887.511248875155, 4910.386201724785, 4919.0899683558655, 4950.282209079735, 2587.702414222351, 2770.7921905857143, 2772.80590358677, 2790.623265859083, 2820.464111818748, 5148.382194136652, 5204.713740744295, 5225.824189557396, 5251.6061295403, 5272.657277277041, 5109.284700141983, 5257.447967963385, 5265.289185959923, 5271.6309858310315, 5293.655912365684, 4957.851264462072, 5149.742512874342, 5224.56324510755, 5252.501012403815, 5275.841724748264, 5149.2276158576515, 5186.559142472932, 5223.2948551945, 5227.424983251915, 5237.16205407133, 5158.690478571298, 5175.430702622783, 5531.363893152178, 5647.971760141173, 5679.949209396275, 5309.33633295838, 5755.165501002415, 5870.442307980735, 5872.148538461985, 5884.205632239764, 4164.958350416457, 4165.687573431944, 4166.500019997619, 4181.83272669102, 4187.958120418757, 5759.0, 5851.970740146442, 5856.067497725655, 5865.178874957424, 5872.207252020922, 5419.810306639249, 5724.599278050663, 5740.454656807597, 5783.479486846204, 5792.4497172768515, 5661.490465857928, 5715.31416230055, 5753.1370294456165, 5772.624779389326, 5836.533077353716, 5546.75039623226, 5586.273784408001, 5612.466815652507, 5705.914411283917, 5763.221965034667, 5804.2744656917885, 5807.7059561473825, 5816.563757718103, 5822.417758224313, 5837.708114594168, 5665.603407761582, 5692.715364231689, 5722.399148089391, 5730.426957304237, 5730.627509211888], "value": 4229.098961818015, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.0101814170499, 835.385991296397, 847.6228078505068, 861.487414988082, 869.02669010708, 14763.852361476382, 15888.126153061577, 15913.363465461382, 15928.681426371495, 15946.521604351874, 16375.59061023475, 16416.507504774847, 16441.26014329355, 16561.337546498155, 16579.585510362245, 733.1861633586715, 838.3083955736512, 861.3539845116147, 866.7139843851514, 875.8248350329959, 883.0904168706243, 885.7652722028668, 888.0409158109227, 889.3241136736095, 889.5196593839352, 922.4649703172178, 922.6263363337858, 923.1507013547518, 924.1405492891641, 924.4730503612943, 755.1806290175167, 884.5533005832013, 904.2630256341126, 909.0364213933186, 920.7329874336434, 668.5754545863363, 669.1735706402588, 669.5480550628306, 669.6718607882151, 669.7454967112466, 15735.055896646123, 15739.68520629581, 15797.289121989537, 15798.526044218692, 15815.367385304575, 14463.927680361636, 14814.925925370411, 15032.624184395369, 15088.24558772057, 15216.08703477795, 13783.862161378358, 14093.154410735286, 14146.894002623074, 14187.43250269988, 14478.276086195649, 4605.608523275526, 4687.437507499088, 4751.3348131261655, 4758.881027974294, 4775.594074503637, 79280.20719792786, 81602.0, 81748.59125704333, 84668.57665711694, 85361.0, 12560.120791544505, 12718.300493472947, 12760.6809829755, 12825.294608796607, 12964.870351296555, 23342.533149337258, 23483.41291467727, 23488.88255558711, 23874.164404245766, 23896.522069558858, 8413.579321033923, 8440.915590844135, 8698.21716045559, 8716.08481109487, 8963.641454341754, 4928.704277743347, 4953.380827396575, 4968.503149685002, 4991.600671946236, 5043.52086551777, 3435.2442462658173, 3522.330757156132, 3524.3656141894703, 3532.5054492371087, 3546.414841551167, 636.748484348689, 650.7917466410703, 655.8653529394174, 660.814971807885, 670.9932900670931, 723.7286017743346, 725.7568714480708, 726.393461459687, 741.3698356397127, 748.8464864702723, 9814.46020468881, 9991.600335986479, 10151.187904967584, 10325.793484130276, 10370.585176592851, 620.4478014567039, 627.4948666323562, 668.1514476614672, 706.8091615263953, 764.1785081037907, 4948.356713627206, 5134.178531434953, 5138.177891537336, 5155.355580552431, 5193.37679478465, 111106.0, 111670.0, 111851.0, 114209.42895285471, 120877.39561302314, 95458.0, 98915.50542247243, 99547.0, 103037.48481257545, 105703.0, 70799.29200707928, 92019.07980920239, 93361.5331923336, 93803.18590442161, 93852.53073734588, 8812.383133180616, 8824.691135810215, 8861.069587693199, 8875.112488751074, 8993.415427997159, 52779.73610131925, 53948.0, 53951.0, 55925.44074559202, 56615.86768264694, 69939.0, 80941.0, 82025.17974820176, 83682.0, 85536.14463855281, 11466.656000320003, 11846.585369512026, 12164.75670486603, 12252.632421027027, 12638.431270592662, 10933.61732339396, 12532.49870005226, 12933.482660693468, 13520.526781562598, 14027.0, 14269.500567480089, 14357.56927292183, 14414.711705766036, 14546.854531454974, 15053.172075535951, 12340.568080117511, 12899.484020639446, 13224.669383265495, 14800.925995369955, 15681.451149209723, 11309.264897781617, 11615.128865335302, 11771.0, 11811.881881181422, 11843.703907402383, 11272.154588405738, 11359.886401135884, 11408.543658253577, 11600.535978560763, 11673.941630291794, 11655.708607284885, 11699.122565807429, 11793.469293881968, 11928.463219154984, 12047.216930899463], "value": 7857.585177340425, "unit": "score"}], "name": "dromaeo_css", "value": 7510.659116527992, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 103.0, 111.0, 108.0, 110.0, 109.0, 111.0, 110.0, 109.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 131.0, 169.0, 123.0, 125.0, 160.0, 124.0, 153.0, 126.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 160.0, 162.0, 160.0, 161.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 126.0, 76.0, 78.0, 76.0, 74.0, 97.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 107.0, 84.0, 84.0, 85.0, 112.0, 85.0, 84.0, 123.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 53.0, 49.0, 52.0, 53.0, 50.0, 51.0, 50.0, 51.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 76.0, 75.0, 76.0, 77.0, 77.0, 76.0, 117.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 119.0, 128.0, 125.0, 116.0, 115.0, 117.0, 123.0, 141.0, 123.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 152.0, 151.0, 153.0, 150.0, 153.0, 151.0, 150.0, 149.0, 149.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 64.0, 61.0, 63.0, 60.0, 61.0, 61.0, 62.0, 59.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1468.1000000000001, "alertThreshold": 2.0}]} 03:52:36 INFO - Return code: 0 03:52:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:52:36 ERROR - # TBPL SUCCESS # 03:52:36 INFO - Running post-action listener: _resource_record_post_action 03:52:36 INFO - ##### 03:52:36 INFO - ##### Finished run-tests step (success) 03:52:36 INFO - ##### 03:52:36 INFO - Running post-run listener: _resource_record_post_run 03:52:36 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 286720; Write bytes: 503619584; Read time: 32; Write time: 819832 03:52:36 INFO - TinderboxPrint: CPU usage
13.2% 03:52:36 INFO - TinderboxPrint: I/O read bytes / time
286,720 / 32 03:52:36 INFO - TinderboxPrint: I/O write bytes / time
503,619,584 / 819,832 03:52:36 INFO - TinderboxPrint: CPU idle
8,253.9 (86.8%) 03:52:36 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 03:52:36 INFO - TinderboxPrint: Swap in / out
0 / 0 03:52:36 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 226725888; Read time: 0; Write time: 480796 03:52:36 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 250691584; Read time: 20; Write time: 274404 03:52:37 INFO - Running post-run listener: _upload_blobber_files 03:52:37 INFO - Blob upload gear active. 03:52:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:52:37 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 03:52:37 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'] 03:52:37 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 03:52:37 INFO - (blobuploader) - INFO - Open directory for files ... 03:52:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:52:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:52:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:41 INFO - (blobuploader) - INFO - Done attempting. 03:52:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:52:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:52:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:42 INFO - (blobuploader) - INFO - Done attempting. 03:52:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:52:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:52:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:43 INFO - (blobuploader) - INFO - Done attempting. 03:52:43 INFO - (blobuploader) - INFO - Iteration through files over. 03:52:43 INFO - Return code: 0 03:52:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:52:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:52:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ebaf4f7d288a39e0dcef7815b6ecea986091ded441e0e321b9a73fc3f4133aaf038c889a5604ca2dbc145809383075586920988d2eee3ef43d3d185006525c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfd91441373e1790274205b35b9506e834fbf0477c1266169c9f92f406eaa25a4398e794b652679d8f07e6a348dc19d0b23b3c72f0ea01cb7a3bc1a9a09183e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c2282be63d0d661b13c0342e48930e9457d29f6a4fb8e699e73cd4d8446b31607ccef2a84a52192f54019d3414316b7cb52f75713ff0afdb9365689df1ec551"} 03:52:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:52:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:52:43 INFO - Contents: 03:52:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ebaf4f7d288a39e0dcef7815b6ecea986091ded441e0e321b9a73fc3f4133aaf038c889a5604ca2dbc145809383075586920988d2eee3ef43d3d185006525c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfd91441373e1790274205b35b9506e834fbf0477c1266169c9f92f406eaa25a4398e794b652679d8f07e6a348dc19d0b23b3c72f0ea01cb7a3bc1a9a09183e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c2282be63d0d661b13c0342e48930e9457d29f6a4fb8e699e73cd4d8446b31607ccef2a84a52192f54019d3414316b7cb52f75713ff0afdb9365689df1ec551"} 03:52:43 INFO - Running post-run listener: copy_logs_to_upload_dir 03:52:43 INFO - Copying logs to upload dir... 03:52:43 INFO - mkdir: /builds/slave/test/build/upload/logs 03:52:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.609835 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-07-16 03:52:42.964111) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-16 03:52:42.967862) ========= 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=df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488 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/1ebaf4f7d288a39e0dcef7815b6ecea986091ded441e0e321b9a73fc3f4133aaf038c889a5604ca2dbc145809383075586920988d2eee3ef43d3d185006525c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfd91441373e1790274205b35b9506e834fbf0477c1266169c9f92f406eaa25a4398e794b652679d8f07e6a348dc19d0b23b3c72f0ea01cb7a3bc1a9a09183e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c2282be63d0d661b13c0342e48930e9457d29f6a4fb8e699e73cd4d8446b31607ccef2a84a52192f54019d3414316b7cb52f75713ff0afdb9365689df1ec551"} build_url:https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011386 build_url: 'https://queue.taskcluster.net/v1/task/I-p95xQwSlmebYH2xCgUcw/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/1ebaf4f7d288a39e0dcef7815b6ecea986091ded441e0e321b9a73fc3f4133aaf038c889a5604ca2dbc145809383075586920988d2eee3ef43d3d185006525c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfd91441373e1790274205b35b9506e834fbf0477c1266169c9f92f406eaa25a4398e794b652679d8f07e6a348dc19d0b23b3c72f0ea01cb7a3bc1a9a09183e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c2282be63d0d661b13c0342e48930e9457d29f6a4fb8e699e73cd4d8446b31607ccef2a84a52192f54019d3414316b7cb52f75713ff0afdb9365689df1ec551"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-16 03:52:42.995824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:52:42.996175) ========= 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=df17445c3590ff7897617a0e00000225-1468664970.955323-1540211488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004418 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:52:43.081421) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 03:52:43.081905) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 03:52:43.082457) ========= ========= Total master_lag: 0.19 =========