builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1467858540.73 results: success (0) buildid: 20160706164923 builduid: cf5d7723f75c49b7843667d4b06ac06d revision: c1342bdc08a949c74278e59958dbecbc6f138258 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.730867) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.731290) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.731594) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.775661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.775917) ========= 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-1467855093.274074-1619602952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-06 19:29:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-07-06 19:29:01 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133586 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.923947) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.924230) ========= 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-1467855093.274074-1619602952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020168 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:00.973894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 19:29:00.974196) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c1342bdc08a949c74278e59958dbecbc6f138258 --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 c1342bdc08a949c74278e59958dbecbc6f138258 --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-1467855093.274074-1619602952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-06 19:29:01,566 truncating revision to first 12 chars 2016-07-06 19:29:01,566 Setting DEBUG logging. 2016-07-06 19:29:01,566 attempt 1/10 2016-07-06 19:29:01,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c1342bdc08a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 19:29:02,571 unpacking tar archive at: mozilla-aurora-c1342bdc08a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.440320 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 19:29:02.429122) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:02.429522) ========= script_repo_revision: c1342bdc08a949c74278e59958dbecbc6f138258 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:02.429927) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:02.430230) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 19:29:02.441677) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-06 19:29:02.442042) ========= /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-1467855093.274074-1619602952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:29:03 INFO - MultiFileLogger online at 20160706 19:29:03 in /builds/slave/test 19:29:03 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 19:29:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:29:03 INFO - {'append_to_log': False, 19:29:03 INFO - 'base_work_dir': '/builds/slave/test', 19:29:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:29:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:29:03 INFO - 'branch': 'Mozilla-Aurora', 19:29:03 INFO - 'buildbot_json_path': 'buildprops.json', 19:29:03 INFO - 'config_files': ('talos/linux_config.py',), 19:29:03 INFO - 'default_actions': ('clobber', 19:29:03 INFO - 'read-buildbot-config', 19:29:03 INFO - 'download-and-extract', 19:29:03 INFO - 'populate-webroot', 19:29:03 INFO - 'create-virtualenv', 19:29:03 INFO - 'install', 19:29:03 INFO - 'run-tests'), 19:29:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:29:03 INFO - 'download_minidump_stackwalk': True, 19:29:03 INFO - 'download_symbols': 'ondemand', 19:29:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:29:03 INFO - 'tooltool.py': '/tools/tooltool.py', 19:29:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:29:03 INFO - '/tools/misc-python/virtualenv.py')}, 19:29:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:29:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:29:03 INFO - 'installer_path': 'installer.exe', 19:29:03 INFO - 'log_level': 'info', 19:29:03 INFO - 'log_name': 'talos', 19:29:03 INFO - 'log_to_console': True, 19:29:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:29:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:29:03 INFO - 'opt_config_files': (), 19:29:03 INFO - 'pip_index': False, 19:29:03 INFO - 'sps_profile': False, 19:29:03 INFO - 'sps_profile_interval': 0, 19:29:03 INFO - 'suite': 'dromaeojs', 19:29:03 INFO - 'system_bits': '32', 19:29:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:29:03 INFO - 'title': 'talos-linux64-ix-021', 19:29:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:29:03 INFO - 'use_talos_json': True, 19:29:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:29:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:29:03 INFO - 'work_dir': 'build'} 19:29:03 INFO - ##### 19:29:03 INFO - ##### Running clobber step. 19:29:03 INFO - ##### 19:29:03 INFO - Running pre-action listener: _resource_record_pre_action 19:29:03 INFO - Running main action method: clobber 19:29:03 INFO - rmtree: /builds/slave/test/build 19:29:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:29:04 INFO - Running post-action listener: _resource_record_post_action 19:29:04 INFO - ##### 19:29:04 INFO - ##### Finished clobber step (success) 19:29:04 INFO - ##### 19:29:04 INFO - ##### 19:29:04 INFO - ##### Running read-buildbot-config step. 19:29:04 INFO - ##### 19:29:04 INFO - Running pre-action listener: _resource_record_pre_action 19:29:04 INFO - Running main action method: read_buildbot_config 19:29:04 INFO - Using buildbot properties: 19:29:04 INFO - { 19:29:04 INFO - "project": "", 19:29:04 INFO - "product": "firefox", 19:29:04 INFO - "script_repo_revision": "production", 19:29:04 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:29:04 INFO - "repository": "", 19:29:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:29:04 INFO - "buildid": "20160706164923", 19:29:04 INFO - "pgo_build": "True", 19:29:04 INFO - "basedir": "/builds/slave/test", 19:29:04 INFO - "buildnumber": 352, 19:29:04 INFO - "slavename": "talos-linux64-ix-021", 19:29:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:29:04 INFO - "platform": "ubuntu64_hw", 19:29:04 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:29:04 INFO - "branch": "mozilla-aurora", 19:29:04 INFO - "repo_path": "releases/mozilla-aurora", 19:29:04 INFO - "revision": "c1342bdc08a949c74278e59958dbecbc6f138258", 19:29:04 INFO - "stage_platform": "linux64-pgo", 19:29:04 INFO - "builduid": "cf5d7723f75c49b7843667d4b06ac06d", 19:29:04 INFO - "slavebuilddir": "test" 19:29:04 INFO - } 19:29:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 19:29:04 INFO - Running post-action listener: _resource_record_post_action 19:29:04 INFO - ##### 19:29:04 INFO - ##### Finished read-buildbot-config step (success) 19:29:04 INFO - ##### 19:29:04 INFO - ##### 19:29:04 INFO - ##### Running download-and-extract step. 19:29:04 INFO - ##### 19:29:04 INFO - Running pre-action listener: _resource_record_pre_action 19:29:04 INFO - Running main action method: download_and_extract 19:29:04 INFO - mkdir: /builds/slave/test/build/tests 19:29:04 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')]} 19:29:04 INFO - https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:29:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 19:29:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 19:29:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:06 INFO - Downloaded 1361 bytes. 19:29:06 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 19:29:06 INFO - Using the following test package requirements: 19:29:06 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 19:29:06 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:29:06 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:29:06 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'jsshell-linux-x86_64.zip'], 19:29:06 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:29:06 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 19:29:06 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:29:06 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:29:06 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:29:06 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 19:29:06 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:29:06 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:29: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')]} 19:29:06 INFO - https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:29:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 19:29:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 19:29:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:25 INFO - Downloaded 24526649 bytes. 19:29:25 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'] 19:29:25 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 19:29:26 INFO - Return code: 0 19:29:26 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 19:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:29:26 INFO - https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:29:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 19:29:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 19:29:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:28 INFO - Downloaded 24526649 bytes. 19:29:28 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'] 19:29:28 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 19:29:29 INFO - Return code: 0 19:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:29:29 INFO - https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:29:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 19:29:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 19:29:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:30 INFO - Downloaded 10833369 bytes. 19:29:30 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'] 19:29:30 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 19:29:30 INFO - Return code: 0 19:29: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')]} 19:29:30 INFO - https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:29:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 19:29:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 19:29:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EdJhTECcTwqoMOLmYmsnKg/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 19:29:32 INFO - Downloaded 63634791 bytes. 19:29:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 19:29:32 INFO - mkdir: /builds/slave/test/properties 19:29:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:29:32 INFO - Writing to file /builds/slave/test/properties/build_url 19:29:32 INFO - Contents: 19:29:32 INFO - build_url:https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 19:29:32 INFO - Running post-action listener: _resource_record_post_action 19:29:32 INFO - Running post-action listener: set_extra_try_arguments 19:29:32 INFO - ##### 19:29:32 INFO - ##### Finished download-and-extract step (success) 19:29:32 INFO - ##### 19:29:32 INFO - ##### 19:29:32 INFO - ##### Running populate-webroot step. 19:29:32 INFO - ##### 19:29:32 INFO - Running pre-action listener: _resource_record_pre_action 19:29:32 INFO - Running main action method: populate_webroot 19:29:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:29:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:29:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:29:32 INFO - '--tppagecycles', 19:29:32 INFO - '7'], 19:29:32 INFO - 'tests': ['tsvgm']}}, 19:29:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:29:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:29:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:29:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:29:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['damp', 'tps']}, 19:29:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'tests': ['damp', 'tps']}, 19:29:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['dromaeo_dom']}, 19:29:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:29:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['a11yr', 19:29:32 INFO - 'ts_paint', 19:29:32 INFO - 'tpaint', 19:29:32 INFO - 'sessionrestore', 19:29:32 INFO - 'sessionrestore_no_auto_restore', 19:29:32 INFO - 'tabpaint']}, 19:29:32 INFO - 'other-e10s': {'tests': ['a11yr', 19:29:32 INFO - 'ts_paint', 19:29:32 INFO - 'tpaint', 19:29:32 INFO - 'sessionrestore', 19:29:32 INFO - 'sessionrestore_no_auto_restore', 19:29:32 INFO - 'tabpaint']}, 19:29:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:29:32 INFO - 'ts_paint', 19:29:32 INFO - 'tpaint', 19:29:32 INFO - 'sessionrestore', 19:29:32 INFO - 'sessionrestore_no_auto_restore', 19:29:32 INFO - 'tabpaint']}, 19:29:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:29:32 INFO - 'ts_paint', 19:29:32 INFO - 'tpaint', 19:29:32 INFO - 'sessionrestore', 19:29:32 INFO - 'sessionrestore_no_auto_restore', 19:29:32 INFO - 'tabpaint']}, 19:29:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['a11yr', 19:29:32 INFO - 'ts_paint', 19:29:32 INFO - 'tpaint', 19:29:32 INFO - 'sessionrestore', 19:29:32 INFO - 'sessionrestore_no_auto_restore', 19:29:32 INFO - 'tabpaint']}, 19:29:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['a11yr', 19:29:32 INFO - 'ts_paint', 19:29:32 INFO - 'tpaint', 19:29:32 INFO - 'sessionrestore', 19:29:32 INFO - 'sessionrestore_no_auto_restore', 19:29:32 INFO - 'tabpaint']}, 19:29:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['tsvgx', 19:29:32 INFO - 'tsvgr_opacity', 19:29:32 INFO - 'tart', 19:29:32 INFO - 'tscrollx', 19:29:32 INFO - 'cart']}, 19:29:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:29:32 INFO - 'tsvgr_opacity', 19:29:32 INFO - 'tart', 19:29:32 INFO - 'tscrollx', 19:29:32 INFO - 'cart']}, 19:29:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'talos_options': ['--disable-e10s'], 19:29:32 INFO - 'tests': ['tp5o']}, 19:29:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'tests': ['tp5o']}, 19:29:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'talos_options': ['--disable-e10s', 19:29:32 INFO - '--xperf_path', 19:29:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:32 INFO - 'tests': ['tp5n']}, 19:29:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:29:32 INFO - 'talos_options': ['--xperf_path', 19:29:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:32 INFO - 'tests': ['tp5n']}}, 19:29:32 INFO - 'talos.zip': {'path': '', 19:29:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:29:32 INFO - Running post-action listener: _resource_record_post_action 19:29:32 INFO - ##### 19:29:32 INFO - ##### Finished populate-webroot step (success) 19:29:32 INFO - ##### 19:29:32 INFO - ##### 19:29:32 INFO - ##### Running create-virtualenv step. 19:29:32 INFO - ##### 19:29:32 INFO - Running pre-action listener: _resource_record_pre_action 19:29:32 INFO - Running main action method: create_virtualenv 19:29:32 INFO - Creating virtualenv /builds/slave/test/build/venv 19:29:32 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 19:29:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:29:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:29:33 INFO - Using real prefix '/usr' 19:29:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:29:33 INFO - Installing distribute.............................................................................................................................................................................................done. 19:29:35 INFO - Installing pip.................done. 19:29:35 INFO - Return code: 0 19:29:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:29:35 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')]} 19:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:35 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')]} 19:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:35 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:35 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 19:29:35 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 19:29:35 INFO - Using env: {'DISPLAY': ':0', 19:29:35 INFO - 'HOME': '/home/cltbld', 19:29:35 INFO - 'LANG': 'en_US.UTF-8', 19:29:35 INFO - 'LANGUAGE': 'en_US:en', 19:29:35 INFO - 'LOGNAME': 'cltbld', 19:29:35 INFO - 'MAIL': '/var/mail/cltbld', 19:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:35 INFO - 'MOZ_NO_REMOTE': '1', 19:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:35 INFO - 'NO_EM_RESTART': '1', 19:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:35 INFO - 'PWD': '/builds/slave/test', 19:29:35 INFO - 'SHELL': '/bin/bash', 19:29:35 INFO - 'SHLVL': '1', 19:29:35 INFO - 'TERM': 'linux', 19:29:35 INFO - 'TMOUT': '86400', 19:29:35 INFO - 'USER': 'cltbld', 19:29:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:35 INFO - '_': '/tools/buildbot/bin/python'} 19:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:35 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 19:29:35 INFO - Cleaning up... 19:29:35 INFO - Return code: 0 19:29:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:29:35 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')]} 19:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:35 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')]} 19:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:35 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:35 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 19:29:35 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 19:29:35 INFO - Using env: {'DISPLAY': ':0', 19:29:35 INFO - 'HOME': '/home/cltbld', 19:29:35 INFO - 'LANG': 'en_US.UTF-8', 19:29:35 INFO - 'LANGUAGE': 'en_US:en', 19:29:35 INFO - 'LOGNAME': 'cltbld', 19:29:35 INFO - 'MAIL': '/var/mail/cltbld', 19:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:35 INFO - 'MOZ_NO_REMOTE': '1', 19:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:35 INFO - 'NO_EM_RESTART': '1', 19:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:35 INFO - 'PWD': '/builds/slave/test', 19:29:35 INFO - 'SHELL': '/bin/bash', 19:29:35 INFO - 'SHLVL': '1', 19:29:35 INFO - 'TERM': 'linux', 19:29:35 INFO - 'TMOUT': '86400', 19:29:35 INFO - 'USER': 'cltbld', 19:29:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:35 INFO - '_': '/tools/buildbot/bin/python'} 19:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:35 INFO - Downloading/unpacking psutil>=3.1.1 19:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:29:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:29:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:29:38 INFO - Installing collected packages: psutil 19:29:38 INFO - Running setup.py install for psutil 19:29:38 INFO - building 'psutil._psutil_linux' extension 19:29:38 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 19:29:38 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 19:29:38 INFO - building 'psutil._psutil_posix' extension 19:29:38 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 19:29:38 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 19:29:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:29:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:29:38 INFO - Successfully installed psutil 19:29:38 INFO - Cleaning up... 19:29:38 INFO - Return code: 0 19:29:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:29:38 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')]} 19:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:38 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')]} 19:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:38 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:38 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 19:29:38 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 19:29:38 INFO - Using env: {'DISPLAY': ':0', 19:29:38 INFO - 'HOME': '/home/cltbld', 19:29:38 INFO - 'LANG': 'en_US.UTF-8', 19:29:38 INFO - 'LANGUAGE': 'en_US:en', 19:29:38 INFO - 'LOGNAME': 'cltbld', 19:29:38 INFO - 'MAIL': '/var/mail/cltbld', 19:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:38 INFO - 'MOZ_NO_REMOTE': '1', 19:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:38 INFO - 'NO_EM_RESTART': '1', 19:29:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:38 INFO - 'PWD': '/builds/slave/test', 19:29:38 INFO - 'SHELL': '/bin/bash', 19:29:38 INFO - 'SHLVL': '1', 19:29:38 INFO - 'TERM': 'linux', 19:29:38 INFO - 'TMOUT': '86400', 19:29:38 INFO - 'USER': 'cltbld', 19:29:38 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:38 INFO - '_': '/tools/buildbot/bin/python'} 19:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:29: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 19:29: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 19:29: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 19:29: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 19:29: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 19:29: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 19:29:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:29:40 INFO - Installing collected packages: mozsystemmonitor 19:29:40 INFO - Running setup.py install for mozsystemmonitor 19:29:40 INFO - Successfully installed mozsystemmonitor 19:29:40 INFO - Cleaning up... 19:29:40 INFO - Return code: 0 19:29:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:29:40 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')]} 19:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:40 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')]} 19:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:40 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:40 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 19:29:40 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 19:29:40 INFO - Using env: {'DISPLAY': ':0', 19:29:40 INFO - 'HOME': '/home/cltbld', 19:29:40 INFO - 'LANG': 'en_US.UTF-8', 19:29:40 INFO - 'LANGUAGE': 'en_US:en', 19:29:40 INFO - 'LOGNAME': 'cltbld', 19:29:40 INFO - 'MAIL': '/var/mail/cltbld', 19:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:40 INFO - 'MOZ_NO_REMOTE': '1', 19:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:40 INFO - 'NO_EM_RESTART': '1', 19:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:40 INFO - 'PWD': '/builds/slave/test', 19:29:40 INFO - 'SHELL': '/bin/bash', 19:29:40 INFO - 'SHLVL': '1', 19:29:40 INFO - 'TERM': 'linux', 19:29:40 INFO - 'TMOUT': '86400', 19:29:40 INFO - 'USER': 'cltbld', 19:29:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:40 INFO - '_': '/tools/buildbot/bin/python'} 19:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:41 INFO - Downloading/unpacking jsonschema==2.5.1 19:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:29:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:29:43 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 19:29:43 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 19:29:43 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 19:29:43 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 19:29:43 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 19:29:43 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 19:29:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:29:43 INFO - warning: no files found matching '*.txt' 19:29:43 INFO - no previously-included directories found matching 'build' 19:29:43 INFO - no previously-included directories found matching 'dist' 19:29:43 INFO - no previously-included directories found matching '.git*' 19:29:43 INFO - Installing collected packages: jsonschema, functools32 19:29:43 INFO - Running setup.py install for functools32 19:29:43 INFO - warning: no files found matching '*.txt' 19:29:43 INFO - no previously-included directories found matching 'build' 19:29:43 INFO - no previously-included directories found matching 'dist' 19:29:43 INFO - no previously-included directories found matching '.git*' 19:29:43 INFO - Successfully installed jsonschema functools32 19:29:43 INFO - Cleaning up... 19:29:43 INFO - Return code: 0 19:29:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:29:43 INFO - Using env: {'DISPLAY': ':0', 19:29:43 INFO - 'HOME': '/home/cltbld', 19:29:43 INFO - 'LANG': 'en_US.UTF-8', 19:29:43 INFO - 'LANGUAGE': 'en_US:en', 19:29:43 INFO - 'LOGNAME': 'cltbld', 19:29:43 INFO - 'MAIL': '/var/mail/cltbld', 19:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:43 INFO - 'MOZ_NO_REMOTE': '1', 19:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:43 INFO - 'NO_EM_RESTART': '1', 19:29:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:43 INFO - 'PWD': '/builds/slave/test', 19:29:43 INFO - 'SHELL': '/bin/bash', 19:29:43 INFO - 'SHLVL': '1', 19:29:43 INFO - 'TERM': 'linux', 19:29:43 INFO - 'TMOUT': '86400', 19:29:43 INFO - 'USER': 'cltbld', 19:29:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:43 INFO - '_': '/tools/buildbot/bin/python'} 19:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:43 INFO - Downloading/unpacking blobuploader==1.2.4 19:29:43 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 19:29:43 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 19:29:43 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 19:29:43 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 19:29:43 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 19:29:43 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 19:29:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:29:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:29:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:46 INFO - Downloading docopt-0.6.1.tar.gz 19:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:29:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:29:46 INFO - Running setup.py install for blobuploader 19:29:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:29:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:29:46 INFO - Running setup.py install for requests 19:29:46 INFO - Running setup.py install for docopt 19:29:46 INFO - Successfully installed blobuploader requests docopt 19:29:46 INFO - Cleaning up... 19:29:47 INFO - Return code: 0 19:29:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29:47 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')]} 19:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:47 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')]} 19:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:47 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:47 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 19:29:47 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 19:29:47 INFO - Using env: {'DISPLAY': ':0', 19:29:47 INFO - 'HOME': '/home/cltbld', 19:29:47 INFO - 'LANG': 'en_US.UTF-8', 19:29:47 INFO - 'LANGUAGE': 'en_US:en', 19:29:47 INFO - 'LOGNAME': 'cltbld', 19:29:47 INFO - 'MAIL': '/var/mail/cltbld', 19:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:47 INFO - 'MOZ_NO_REMOTE': '1', 19:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:47 INFO - 'NO_EM_RESTART': '1', 19:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:47 INFO - 'PWD': '/builds/slave/test', 19:29:47 INFO - 'SHELL': '/bin/bash', 19:29:47 INFO - 'SHLVL': '1', 19:29:47 INFO - 'TERM': 'linux', 19:29:47 INFO - 'TMOUT': '86400', 19:29:47 INFO - 'USER': 'cltbld', 19:29:47 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:47 INFO - '_': '/tools/buildbot/bin/python'} 19:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:29:47 INFO - Running setup.py (path:/tmp/pip-vHauGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:29:47 INFO - Running setup.py (path:/tmp/pip-ERq7zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:29:47 INFO - Running setup.py (path:/tmp/pip-ETIC1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:29:47 INFO - Running setup.py (path:/tmp/pip-eY0cCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:29:47 INFO - Running setup.py (path:/tmp/pip-JZ3PgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:29:47 INFO - Running setup.py (path:/tmp/pip-81DiGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:29:47 INFO - Running setup.py (path:/tmp/pip-bZiq7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:29:47 INFO - Running setup.py (path:/tmp/pip-7f2Jfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:29:47 INFO - Running setup.py (path:/tmp/pip-JKIUBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:29:47 INFO - Running setup.py (path:/tmp/pip-XdwtTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:29:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:29:47 INFO - Running setup.py (path:/tmp/pip-upx30C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:29:48 INFO - Running setup.py (path:/tmp/pip-mX9OZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:29:48 INFO - Running setup.py (path:/tmp/pip-xU0HUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:29:48 INFO - Running setup.py (path:/tmp/pip-3DwI7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:48 INFO - Running setup.py (path:/tmp/pip-UjfZSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:29:48 INFO - Running setup.py (path:/tmp/pip-H0ohX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:29:48 INFO - Running setup.py (path:/tmp/pip-fcm45D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:29:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:29:48 INFO - Running setup.py install for manifestparser 19:29:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:29:48 INFO - Running setup.py install for mozcrash 19:29:48 INFO - Running setup.py install for mozdebug 19:29:48 INFO - Running setup.py install for mozdevice 19:29:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:29:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:29:48 INFO - Running setup.py install for mozfile 19:29:48 INFO - Running setup.py install for mozhttpd 19:29:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Running setup.py install for mozinfo 19:29:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Running setup.py install for mozInstall 19:29:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Running setup.py install for mozleak 19:29:49 INFO - Running setup.py install for mozlog 19:29:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Running setup.py install for moznetwork 19:29:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Running setup.py install for mozprocess 19:29:49 INFO - Running setup.py install for mozprofile 19:29:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Running setup.py install for mozrunner 19:29:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:29:49 INFO - Running setup.py install for mozscreenshot 19:29:50 INFO - Running setup.py install for moztest 19:29:50 INFO - Running setup.py install for mozversion 19:29:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:29:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:29:50 INFO - Cleaning up... 19:29:50 INFO - Return code: 0 19:29:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29:50 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')]} 19:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:50 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')]} 19:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:50 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:50 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 19:29:50 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 19:29:50 INFO - Using env: {'DISPLAY': ':0', 19:29:50 INFO - 'HOME': '/home/cltbld', 19:29:50 INFO - 'LANG': 'en_US.UTF-8', 19:29:50 INFO - 'LANGUAGE': 'en_US:en', 19:29:50 INFO - 'LOGNAME': 'cltbld', 19:29:50 INFO - 'MAIL': '/var/mail/cltbld', 19:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:50 INFO - 'MOZ_NO_REMOTE': '1', 19:29:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:50 INFO - 'NO_EM_RESTART': '1', 19:29:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:50 INFO - 'PWD': '/builds/slave/test', 19:29:50 INFO - 'SHELL': '/bin/bash', 19:29:50 INFO - 'SHLVL': '1', 19:29:50 INFO - 'TERM': 'linux', 19:29:50 INFO - 'TMOUT': '86400', 19:29:50 INFO - 'USER': 'cltbld', 19:29:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:50 INFO - '_': '/tools/buildbot/bin/python'} 19:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:29:50 INFO - Running setup.py (path:/tmp/pip-YtaBO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:29:50 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)) 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:29:50 INFO - Running setup.py (path:/tmp/pip-9odn6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:29:50 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)) 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:29:50 INFO - Running setup.py (path:/tmp/pip-X92mbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:29:50 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)) 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:29:50 INFO - Running setup.py (path:/tmp/pip-Sh9QMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:29:50 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)) 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:29:50 INFO - Running setup.py (path:/tmp/pip-1RxO9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:29:50 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)) 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:29:50 INFO - Running setup.py (path:/tmp/pip-Yu1E5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:29:50 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)) 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:29:50 INFO - Running setup.py (path:/tmp/pip-m614yG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:29:50 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)) 19:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:29:50 INFO - Running setup.py (path:/tmp/pip-vk0Lev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:29:51 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)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:29:51 INFO - Running setup.py (path:/tmp/pip-WU6v6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:29:51 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)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:29:51 INFO - Running setup.py (path:/tmp/pip-oZOksS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:29:51 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)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:29:51 INFO - Running setup.py (path:/tmp/pip-QoqOU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:29:51 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)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:29:51 INFO - Running setup.py (path:/tmp/pip-uLCnTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:29:51 INFO - Running setup.py (path:/tmp/pip-skaS8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:29:51 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)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:29:51 INFO - Running setup.py (path:/tmp/pip-KjDQYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:51 INFO - Running setup.py (path:/tmp/pip-021e7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:51 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)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:29:51 INFO - Running setup.py (path:/tmp/pip-2j3jJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:29:51 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)) 19:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:29:51 INFO - Running setup.py (path:/tmp/pip-ZDljVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:29:51 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)) 19:29:51 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)) 19:29:51 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)) 19:29:51 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)) 19:29:51 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)) 19:29:51 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)) 19:29:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:53 INFO - Downloading blessings-1.6.tar.gz 19:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:29:53 INFO - Installing collected packages: blessings 19:29:53 INFO - Running setup.py install for blessings 19:29:53 INFO - Successfully installed blessings 19:29:53 INFO - Cleaning up... 19:29:53 INFO - Return code: 0 19:29:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:29:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:29:54 INFO - Reading from file tmpfile_stdout 19:29:54 INFO - Current package versions: 19:29:54 INFO - argparse == 1.2.1 19:29:54 INFO - blessings == 1.6 19:29:54 INFO - blobuploader == 1.2.4 19:29:54 INFO - docopt == 0.6.1 19:29:54 INFO - functools32 == 3.2.3-2 19:29:54 INFO - jsonschema == 2.5.1 19:29:54 INFO - manifestparser == 1.1 19:29:54 INFO - mozInstall == 1.12 19:29:54 INFO - mozcrash == 0.17 19:29:54 INFO - mozdebug == 0.1 19:29:54 INFO - mozdevice == 0.48 19:29:54 INFO - mozfile == 1.2 19:29:54 INFO - mozhttpd == 0.7 19:29:54 INFO - mozinfo == 0.9 19:29:54 INFO - mozleak == 0.1 19:29:54 INFO - mozlog == 3.2 19:29:54 INFO - moznetwork == 0.27 19:29:54 INFO - mozprocess == 0.22 19:29:54 INFO - mozprofile == 0.28 19:29:54 INFO - mozrunner == 6.11 19:29:54 INFO - mozscreenshot == 0.1 19:29:54 INFO - mozsystemmonitor == 0.3 19:29:54 INFO - moztest == 0.7 19:29:54 INFO - mozversion == 1.4 19:29:54 INFO - psutil == 3.1.1 19:29:54 INFO - requests == 1.2.3 19:29:54 INFO - wsgiref == 0.1.2 19:29:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29:54 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')]} 19:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:54 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')]} 19:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:54 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:54 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 19:29:54 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 19:29:54 INFO - Using env: {'DISPLAY': ':0', 19:29:54 INFO - 'HOME': '/home/cltbld', 19:29:54 INFO - 'LANG': 'en_US.UTF-8', 19:29:54 INFO - 'LANGUAGE': 'en_US:en', 19:29:54 INFO - 'LOGNAME': 'cltbld', 19:29:54 INFO - 'MAIL': '/var/mail/cltbld', 19:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:54 INFO - 'MOZ_NO_REMOTE': '1', 19:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:54 INFO - 'NO_EM_RESTART': '1', 19:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:54 INFO - 'PWD': '/builds/slave/test', 19:29:54 INFO - 'SHELL': '/bin/bash', 19:29:54 INFO - 'SHLVL': '1', 19:29:54 INFO - 'TERM': 'linux', 19:29:54 INFO - 'TMOUT': '86400', 19:29:54 INFO - 'USER': 'cltbld', 19:29:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:54 INFO - '_': '/tools/buildbot/bin/python'} 19:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 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)) 19:29:54 INFO - Cleaning up... 19:29:54 INFO - Return code: 0 19:29:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:29:54 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')]} 19:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:54 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')]} 19:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:54 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:54 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 19:29:54 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 19:29:54 INFO - Using env: {'DISPLAY': ':0', 19:29:54 INFO - 'HOME': '/home/cltbld', 19:29:54 INFO - 'LANG': 'en_US.UTF-8', 19:29:54 INFO - 'LANGUAGE': 'en_US:en', 19:29:54 INFO - 'LOGNAME': 'cltbld', 19:29:54 INFO - 'MAIL': '/var/mail/cltbld', 19:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:54 INFO - 'MOZ_NO_REMOTE': '1', 19:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:54 INFO - 'NO_EM_RESTART': '1', 19:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:54 INFO - 'PWD': '/builds/slave/test', 19:29:54 INFO - 'SHELL': '/bin/bash', 19:29:54 INFO - 'SHLVL': '1', 19:29:54 INFO - 'TERM': 'linux', 19:29:54 INFO - 'TMOUT': '86400', 19:29:54 INFO - 'USER': 'cltbld', 19:29:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:54 INFO - '_': '/tools/buildbot/bin/python'} 19:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:29:54 INFO - Cleaning up... 19:29:54 INFO - Return code: 0 19:29:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:29:54 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')]} 19:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:54 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')]} 19:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:54 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 0x7fc11c40bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe69350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, '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-1467855093.274074-1619602952', '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 19:29:54 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 19:29:54 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 19:29:54 INFO - Using env: {'DISPLAY': ':0', 19:29:54 INFO - 'HOME': '/home/cltbld', 19:29:54 INFO - 'LANG': 'en_US.UTF-8', 19:29:54 INFO - 'LANGUAGE': 'en_US:en', 19:29:54 INFO - 'LOGNAME': 'cltbld', 19:29:54 INFO - 'MAIL': '/var/mail/cltbld', 19:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:54 INFO - 'MOZ_NO_REMOTE': '1', 19:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:29:54 INFO - 'NO_EM_RESTART': '1', 19:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:54 INFO - 'PWD': '/builds/slave/test', 19:29:54 INFO - 'SHELL': '/bin/bash', 19:29:54 INFO - 'SHLVL': '1', 19:29:54 INFO - 'TERM': 'linux', 19:29:54 INFO - 'TMOUT': '86400', 19:29:54 INFO - 'USER': 'cltbld', 19:29:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:54 INFO - '_': '/tools/buildbot/bin/python'} 19:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:54 INFO - Downloading/unpacking flake8 19:29:54 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 19:29:54 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 19:29:54 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 19:29:54 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 19:29:54 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 19:29:54 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 19:29:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:29:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:29:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:29:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:29:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:29:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:29:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:29:57 INFO - no previously-included directories found matching 'docs/_build' 19:29:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:29:57 INFO - Running setup.py install for pep8 19:29:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:29:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:29:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:29:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:29:57 INFO - no previously-included directories found matching 'docs/_build' 19:29:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:29:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:29:57 INFO - Cleaning up... 19:29:57 INFO - Return code: 0 19:29:57 INFO - Running post-action listener: _resource_record_post_action 19:29:57 INFO - Running post-action listener: _start_resource_monitoring 19:29:57 INFO - Starting resource monitoring. 19:29:57 INFO - ##### 19:29:57 INFO - ##### Finished create-virtualenv step (success) 19:29:57 INFO - ##### 19:29:57 INFO - ##### 19:29:57 INFO - ##### Running install step. 19:29:57 INFO - ##### 19:29:57 INFO - Running pre-action listener: _resource_record_pre_action 19:29:57 INFO - Running main action method: install 19:29:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:29:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:29:57 INFO - Reading from file tmpfile_stdout 19:29:57 INFO - Detecting whether we're running mozinstall >=1.0... 19:29:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:29:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:29:57 INFO - Reading from file tmpfile_stdout 19:29:57 INFO - Output received: 19:29:57 INFO - Usage: mozinstall [options] installer 19:29:57 INFO - Options: 19:29:57 INFO - -h, --help show this help message and exit 19:29:57 INFO - -d DEST, --destination=DEST 19:29:57 INFO - Directory to install application into. [default: 19:29:57 INFO - "/builds/slave/test"] 19:29:57 INFO - --app=APP Application being installed. [default: firefox] 19:29:57 INFO - mkdir: /builds/slave/test/build/application 19:29:57 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'] 19:29:57 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 19:30:14 INFO - Reading from file tmpfile_stdout 19:30:14 INFO - Output received: 19:30:14 INFO - /builds/slave/test/build/application/firefox/firefox 19:30:14 INFO - Running post-action listener: _resource_record_post_action 19:30:14 INFO - ##### 19:30:14 INFO - ##### Finished install step (success) 19:30:14 INFO - ##### 19:30:14 INFO - ##### 19:30:14 INFO - ##### Running run-tests step. 19:30:14 INFO - ##### 19:30:14 INFO - Running pre-action listener: _resource_record_pre_action 19:30:14 INFO - Running main action method: run_tests 19:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:30:14 INFO - Python 2.7.3 19:30:14 INFO - Return code: 0 19:30:14 INFO - grabbing minidump binary from tooltool 19:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:30:14 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 0xef81c0>, '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 0xef90c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xef9550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:30:14 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 19:30:14 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 19:30:14 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 19:30:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:30:14 INFO - Return code: 0 19:30:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:30:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:30:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:30:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:30:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:30:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:30:15 INFO - Return code: 0 19:30:15 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/EdJhTECcTwqoMOLmYmsnKg/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 19:30:15 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/EdJhTECcTwqoMOLmYmsnKg/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 19:30:15 INFO - Using env: {'DISPLAY': ':0', 19:30:15 INFO - 'HOME': '/home/cltbld', 19:30:15 INFO - 'LANG': 'en_US.UTF-8', 19:30:15 INFO - 'LANGUAGE': 'en_US:en', 19:30:15 INFO - 'LOGNAME': 'cltbld', 19:30:15 INFO - 'MAIL': '/var/mail/cltbld', 19:30:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:30:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:15 INFO - 'MOZ_NO_REMOTE': '1', 19:30:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:15 INFO - 'NO_EM_RESTART': '1', 19:30:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:15 INFO - 'PWD': '/builds/slave/test', 19:30:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:30:15 INFO - 'SHELL': '/bin/bash', 19:30:15 INFO - 'SHLVL': '1', 19:30:15 INFO - 'TERM': 'linux', 19:30:15 INFO - 'TMOUT': '86400', 19:30:15 INFO - 'USER': 'cltbld', 19:30:15 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467855093.274074-1619602952', 19:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:15 INFO - '_': '/tools/buildbot/bin/python'} 19:30:15 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/EdJhTECcTwqoMOLmYmsnKg/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 19:30:15 INFO - mozversion application_buildid: 20160706164923 19:30:15 INFO - mozversion application_changeset: c1342bdc08a949c74278e59958dbecbc6f138258 19:30:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:30:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:30:15 INFO - mozversion application_name: Firefox 19:30:15 INFO - mozversion application_remotingname: firefox-dev 19:30:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:30:15 INFO - mozversion application_vendor: Mozilla 19:30:15 INFO - mozversion application_version: 49.0a2 19:30:15 INFO - mozversion platform_buildid: 20160706164923 19:30:15 INFO - mozversion platform_changeset: c1342bdc08a949c74278e59958dbecbc6f138258 19:30:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:30:15 INFO - mozversion platform_version: 49.0a2 19:30:15 INFO - using testdate: 1467858615 19:30:15 INFO - actual date: 1467858615 19:30:15 INFO - starting webserver on 'localhost:49987' 19:30:15 INFO - SUITE-START | Running 2 tests 19:30:15 INFO - TEST-START | dromaeo_css 19:30:15 INFO - operating with platform_type : linux_ 19:30:15 INFO - Initialising browser for dromaeo_css test... 19:30:15 INFO - TEST-INFO | started process 18985 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb5gHeP/profile http://localhost:49987/getInfo.html) 19:30:20 INFO - PROCESS | 18985 | __metrics Screen width/height:1600/1200 19:30:20 INFO - PROCESS | 18985 | colorDepth:24 19:30:20 INFO - PROCESS | 18985 | Browser inner width/height: 1024/697 19:30:20 INFO - PROCESS | 18985 | __metrics 19:30:21 ERROR - PROCESS | 18985 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:30:22 INFO - TEST-INFO | 18985: exit 0 19:30:22 INFO - Browser initialized. 19:30:22 INFO - Running cycle 1/1 for dromaeo_css test... 19:30:22 INFO - TEST-INFO | started process 19079 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb5gHeP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:30:34 INFO - PROCESS | 19079 | RSS: Main: 148959232 19:30:34 INFO - PROCESS | 19079 | 19:31:26 INFO - PROCESS | 19079 | 1467858686129 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] 19:33:34 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:49987/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49987/tests/dromaeo/cssquery-ext.html) 19:33:35 INFO - PROCESS | 19079 | RSS: Main: 249266176 19:33:35 INFO - PROCESS | 19079 | 19:36:37 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:49987/tests/dromaeo/cssquery-ext.html (next: http://localhost:49987/tests/dromaeo/cssquery-jquery.html) 19:36:38 INFO - PROCESS | 19079 | RSS: Main: 226852864 19:36:38 INFO - PROCESS | 19079 | 19:39:40 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:49987/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49987/tests/dromaeo/cssquery-mootools.html) 19:39:40 INFO - PROCESS | 19079 | RSS: Main: 221962240 19:39:40 INFO - PROCESS | 19079 | 19:42:42 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:49987/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49987/tests/dromaeo/cssquery-prototype.html) 19:42:43 INFO - PROCESS | 19079 | RSS: Main: 222826496 19:42:43 INFO - PROCESS | 19079 | 19:45:45 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:49987/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49987/tests/dromaeo/cssquery-yui.html) 19:45:46 INFO - PROCESS | 19079 | RSS: Main: 218148864 19:45:46 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | Cycle 1(1): loaded http://localhost:49987/tests/dromaeo/cssquery-yui.html (next: http://localhost:49987/tests/dromaeo/cssquery-dojo.html) 19:48:49 INFO - PROCESS | 19079 | RSS: Main: 258785280 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | __start_tp_report 19:48:49 INFO - PROCESS | 19079 | _x_x_mozilla_page_load 19:48:49 INFO - PROCESS | 19079 | _x_x_mozilla_page_load_details 19:48:49 INFO - PROCESS | 19079 | |i|pagename|runs| 19:48:49 INFO - PROCESS | 19079 | |0;dojo.html;3350.698437140657;3372.258103217292;3378.189234583699;3409.2499650076975;3426.8971930842067;5527.060399732045;5575.776968921244;5589.7205139743;5590.273264475622;5595.38450770415;5507.063799154143;5552.888942221153;5553.8333849984465;5554.50009499145;5592.245046918675;5030.673006254591;5053.242013697938;5064.9746751266375;5077.593792496591;5083.872903177432;5055.974720126413;5127.615428842833;5132.101882170605;5134.794608215667;5136.974315128419;5043.899122017558;5088.287639730441;5089.389273287211;5107.412647545524;5127.589792816566;5203.907179492311;5204.765785539641;5207.505286997729;5216.191490318969;5254.238135470336;4924.8276310328965;4988.176950803113;5018.523240292203;5108.514691104377;5110.259012443212;5398.13629819227;5401.297831281908;5401.972990135064;5409.323834520685;5450.264214331054;6012.3085845127935;6015.39846015395;6016.849578760566;6023.548233882432;6024.578279520435;5941.970290148565;5974.731137098841;5991.2510936132985;6065.272167339904;6089.725962331661;5593.272874526286;5612.242347283146;5635.239242702265;5644.6613203207935;5670.234518340054;5914.763409463573;6148.1699970504305;6154.292256390483;6160.229971253504;6163.84590385244;5810;5813.912791308134;5816.272965879265;5936.198613187251;5948.9702551487835;5977.282726072812;5986.132010858401;5989.371116032841;6010.609310394811;6015.578909476382;5737.713114344266;5830.300363956268;5949.345571987078;5956.851078723066;5964.105384192319;5871.941280587225;5979.342272350038;5984.850378740566;5986.491148252448;6025.487833534111;4588.701734387254;4594.287885377772;4611.469680986663;4613.284940834176;4636.119137363958;4015;4022.3162062449455;4037.7981100944835;4120.979395103005;4158.397032430281;5567.2762540870235;5589.804356847572;5594.916076258859;5598.860028499239;5621.7189140542805;5945.10823376497;5949.196858424144;5949.762009519657;5952.7321270542925;5975.641461512323;5478.424765399655;5518.972405137949;5572.637778544382;5597.412271711493;5612.130119831432;5215.2698622192165;5218.63469557135;5218.7912483500995;5236.293100431393;5246.160614301693;6301.369863013662;6314.71583778731;6316.873662526816;6320.778772742932;6320.999999999908;5824.796132135355;5909.556783241188;5957.880842383041;6049.969750151221;6061.515078793774;6263.154474146114;6281.96859015702;6288.5598008138995;6291.716872740646;6302.054691796267;5892.440218179266;5901.3213480450295;5909.231799866162;5924.7926322578505;5929.733162007807;5801.767929282782;5921.970390148022;5966.642001479925;5969.343372229138;5984.880302393841;5872.735726892215;5872.941270587239;5873.442023007808;5880.970595146997;5883.55873309512;5795.130730390307;5839.678817665069;5843.328017278068;5853.09277062056;5873.4126587340925;5596;5695.1457281406465;5698.515626171822;5724.656520608777;5783.392743761844;5849.590528662953;5944.851378715567;5947.6431414115295;5948.583599148018;5954.3152537458745;5569.331680198402;5753.539716822728;5756.510696590837;5756.597038207286;5757.107648314518 19:48:49 INFO - PROCESS | 19079 | |1;ext.html;581.2153592649923;625.2778041362227;646.4537465841364;648.6951132967504;658.2430205263943;34231.48652770207;37433.81283093584;37783.24433511328;47459.28811067837;48113.278300825514;50347.74826125864;50697;50770.746146269215;50822.74588627052;50876.23685644718;8124.634391452399;9152.130547597968;9300.906990930072;9301.209397201246;9307.720768376927;10782.78434431311;11021.504032318566;11035.558577656884;11405.37270450125;11420.200585958986;11294.548218071268;11406;11425.143114266422;11434.085273178167;11438.599649012287;8522.062573116953;11070.833937490945;11118.22172447929;11140.387278699669;11405.771884562262;10618.36289822613;11045.171612129123;11056.834147487794;11057.447127643585;11070.28043177199;46932.2960155598;47436.76281618604;47446;47529.76235118837;47550.7622461889;3671.467637192619;3774.924501509982;3968.4047392891007;4420.491643461011;4431.2466880630045;36268.63731362679;36375.63624363749;36503.634963650285;36816.63183368159;36908.446373304425;4559.293309537027;4564.087182563468;4567.703099298535;4569.430569430569;4627.930581041287;404998;405393;406666.99999999703;406773;410674;73091;73668.63165684245;74097.6295118521;74130;74284;40833.79583102065;41921.79039104785;41987.16025679469;44542;44610;19795.703064454046;19923.302684405975;19980.60038799216;20001.299954501523;20001.399958001282;13271.535496257584;14803.333849976776;14966.775498367533;15003.32485038176;15031.924840375728;23158.1894633687;23244.65133023006;23258.65112023321;23384.883075584854;23413.88293058558;7960.303420981649;8053.919460805434;8131.83736325282;8190;8262.339012878956;995.2088089968493;1064.6593090211213;1086.7065892209218;1104.0670633314837;1113.5156207049959;34956.12609684778;35341.11647208789;35557;35657.64342356543;35870.64129358725;1120.7982563138714;1149.8620165580016;1153.8903804138592;1207.8429804125408;1241.205628397826;18205.27178912855;18258.36095736643;18466.538336541693;18590.814091859178;18626.90686546586;587464;592568;595129;596982;597228;65765;66121;66755;66763.66618166878;66767;1254.930978796175;1297.4356155072385;1350.3180893648841;1394.986050139506;1396.2250950722243;31431;35117.65116952342;36068.27863442664;36215.456768147975;36281.274374511835;55562;61745.69127154336;62912.37087629248;63088.684556576925;70155.64922175561;406566;409530;413110;413344;414491;40849;46579.301310481045;47105.293420598035;47967.760161200364;49329.50670493249;52713.47286527239;52902;53457.465425345246;54573.72713136409;54674;43723.34414983714;43984.34023489586;44201.77899110484;44619.33071004003;44962.55037449583;44398.59747962934;44754.3286850704;44774;44905.77547112243;45337.319940200265;44805.55194448014;45505.54494455013;46246;46251.53748462472;46254.76872615615;36878.26243475062;39175;39994.800025999684;40243.195136096525;40471.59528404678;29932.850335748182;30447.543286850272;30608.234794130323;30729.846350768104;30824.383512330078 19:48:49 INFO - PROCESS | 19079 | |2;jquery.html;412.78122595024627;419.6349176216691;424.09032625019313;429.17812389274553;439.1721604774998;18734.718979215322;20001.899990500046;20657.38027859165;21032.789672103314;21069.578608427822;19466.221351145938;19499.5125121872;19907.203711851507;20227.898860505677;20272.18911243552;5587.497125258726;5749.166370876221;5776.913346299809;5779.682117483537;5780;4143.751374917515;4375.824967001332;5765.509931655814;5806;6434.903476447857;6467.256265529454;6472.546921715481;6501.057346684728;6505.4145126938565;6516.348365163493;6271.749130034793;6475.805725828209;6607.3392660734025;6621.933780662204;6626.072349871022;5833.300003999505;5841.766329346821;5859.7949071782705;5877.5591830612875;5891.027980383232;20981.16075356984;22049.669254961187;23536.52926941469;23763.76236237633;23798.524029519485;2248.3816950338623;2273.8521996070367;2354.693889794333;2395.916142934989;2929.062699936;10188.541515631814;10376;10537.157027437786;10582.735431614194;10752.301100428525;2020.5754942765316;2033.7051127586565;2043.6423625865564;2071.8238949689294;2078.324544523036;84074.15925840725;85808;86064.56967715122;86530.70203946883;86746.56626716827;11143.275687080313;11594.130440217017;11975.700607484883;11982.88017119835;12063.396830158457;16524.173791310386;16713.080780556942;16757.245923933453;16765.664686706197;16803.41188058412;8704.782380440412;8776.517291549026;8815.515146666867;8848.557572121368;8939.776505587282;8312.792180195544;8419.200175983271;8472.872906906401;8553.315734741205;8706.564671766513;4897.314375987365;4980.12847751642;5115.590752739772;5119.1553393690365;5184.300119483897;1809.1499157335188;2006.8695534790193;2060.258307009475;2061.2682497713395;2105.347342323854;851.5061264466594;943.094629156018;989.4360214677708;1055.904968552834;1101.0695961912172;18565.535861603297;19080;19670.70493942592;19748.716333438282;19759.60480790405;962.6149540183842;966.0725703324887;1015.21828192293;1035.7669524356959;1096.7203363142348;9302.53487325631;10899.400532970603;10974.725631859268;11034.448277586089;11080.722981925517;536189;550545;556727;564054;567625;69739.65130174317;101083.49458252662;101115;107196.4640176794;111618;1046.1421634259834;1064.2656566968767;1084.4577711144427;1095.4632230207142;1103.1064837481827;18098.81901180971;18281.17734701915;18424.723629145843;18428.723569146743;18655.253789848255;44333.33499997438;45178.32232516449;45579.54420455886;46790.064198716515;47026.52973470358;64093.3590664106;64417.67791161014;64592.677036614514;65707.34292657013;66222.33777662161;8866.335024873031;8920.598573064357;8936.776580585538;9146.95426522863;9288.34981551285;12899;13299.46802127904;13450.932745336211;13532.526361577691;13693.931530342285;5851.21008663825;7681.231876812498;8705.782355441164;11856.58501952462;12064.577739779066;11114.610988615359;11525.250858694493;11702.941485292518;11767.23512971654;12066.879331206927;11260.436978151223;11578.94210528942;11693.12301577368;11792.469338879942;12137.514499419924;7465.104187497535;7748.496347737379;7764.456488045783;8027;8788.077251888715;10911.563537458413;11184.384858832842;11345.432728363714;11383.715407114889;11449.14131440162 19:48:49 INFO - PROCESS | 19079 | |3;mootools.html;1415.6673181802269;1479.6596782739969;1547.8208836142414;1721.1996421663932;1912.0344226165794;9304.488253146073;9644.083812037854;9646.083622055894;9938.254630902675;9958.352707074035;9694.224462043037;9877.160441362494;9880.061394167542;9885.40687558747;9886.40681559107;2158.1583182558816;2448.1309135256993;2468.395243165427;2524.507720994407;2562.8334158279745;2503.8622875741826;2507.874606269679;2508.234988328559;2509;2511.5353659572947;2465.9876700616473;2495.463475352799;2517.748225177486;2519.067944860404;2521.6595759572497;2116.75657299411;2351.341624345186;2421.1041914491575;2422.8061755059553;2423.273018094573;2418.7944024757917;2468.136152346682;2494.1270555305678;2494.887730052134;2498.812589055828;9630;9729.56216970238;9736.707898763048;9738.172255358302;9748.512574371252;5591.720413979285;5723.71381430931;5734.369219385864;5734.741936612862;5742.310922689304;3564.8752293669595;3628.6915612172997;3667.1932174921476;3673.687736542397;3725.9068523286865;1162.3316592958997;1186.1696812231387;1189.8988585970117;1216.1912328301669;1234.2347744398442;13662.658433539242;13697.726045479134;13735.313234338244;13739.313034348243;13749;4957.876053098629;5152.175651895679;5167.638265321467;5220.146847869028;5243.449437809051;11224.607138750105;11393.487293071832;11438.942805286088;11443.54225830974;11444.656660300205;5837.591368604157;5848.152017957373;5851.444112809277;5852.912206316909;5859.765609375662;4761.95238047622;5224.9738751306;5345.251664766935;5359.892802144013;5454.863628409322;4375.584319489643;4392.538783427758;4449.510553839075;4493.168763778712;4494;1358.3547814788094;1368.705728268427;1372.457879137735;1376.4012654495327;1378.6484446466086;851.3274513134554;980.645932061644;1019.3017782818793;1059.327327147267;1067.071647666535;6435.388638079375;6437.163168788121;6519.837004074936;6528.477721782246;6731.49513786468;503.7204351584832;505.3985756949219;511.49617626637735;1062.1396668698223;1088.6570730219955;4332.393464914914;4346.47842258931;4348.2390581648715;4389.6927215095275;4392.670549708785;11982.64052078439;12075.27548347102;12141.635750927486;12166.695832604255;12183;11413.086953043736;11831.822522662167;12059.879401206052;12076.939615301868;12081.45633446497;8702.086280940537;8801.295896328278;8873.556322183995;8895.688650897187;8919.643214271486;6254.8123556293385;6450.709718062604;6452.806415807533;6494.512911531749;6519.804405867641;10773.784524309627;11138.665840024812;11195.72010699739;11214.495347709535;11386;11245.325280483197;11271.605493807678;11319.660410187707;11398.772024559628;11400.828987565028;7212.675429605785;7260.092488438945;8196.057453392936;8263.380246481658;8365.707200247962;9410.670626528039;9515.33392662507;9516.429014259167;9534.427934323963;9534.713958580976;8234.341252699889;8482.957585212034;8486.660533578835;8572.957135214285;8656.350773691873;8214.424990250864;8312.501249925024;8360.28937540316;8383.916160838313;8518.829623407373;7540.849183016199;7550.282723141293;7626.7034604116325;7649.196834332315;7650.540967541965;5482.314710661168;5501.724913754377;5538.86152846166;5753.05074662676;5962.284525856925;5737.9139312911175;5809.24479817621;5855.59010869235;5894.469497745052;5946.524278057832 19:48:49 INFO - PROCESS | 19079 | |4;prototype.html;328.2515863830466;328.5859816631045;337.41626985315395;337.9645137260588;339.79612232660423;5418.807862270304;5420.5934554908345;5438.293021907151;5447.700376479297;5494.835154945358;5350.411454739985;5364.892702145955;5371.248025276464;5382.542483888874;5411.161270003156;4122.113745544707;4144.254034273829;4274.893127671802;4277.118068050064;4309.698321117523;4259.446271984638;4277.850275240352;4280.352064455192;4283.67872409569;4308.396824444565;4112.527059388164;4249.213895429355;4251.574842515739;4258.829646814123;4265.31754919213;4155.815592556122;4239.894002649928;4247.362895565674;4247.575242475758;4255.27660297747;3715.1826598148364;3742.2515496900455;3744.6782994543555;3755.9858838113555;3762.0782908187393;5160.458151894035;5220.608454365938;5223.6343455958095;5223.843284701465;5305.5409762315285;4672.509386514401;4908.43552991407;4909.459959404463;4926.236433352836;4929.383827021622;5758.42415758425;5763.337216220147;5772.076467765138;5772.682502462363;5791.594588378815;4116.300228961084;4124.6906482013665;4125.97937010316;4128.4632997710405;4134.37984302357;5592.6085174037835;5888.5877988540815;5942.435468630516;5958.136070269787;5969.641821490724;4132.607402296777;4143.56492568282;4145.8963525912095;4155.148194620096;4171.937420938689;4182.498100227992;4191.296613684157;4204.012057166564;4206.747595144301;4213.894652633709;4715.481297057321;4824.228123500293;4834.927476087862;4875.464228767927;4884.462709101996;5751;5774.48029677331;5792.218050563204;5799.376174671099;5802.622829516068;1423.7223741370333;1438.7050654615778;1454.6436123149685;1455.316001479303;1456.730504856605;916.0198587511433;916.977075573116;921.4056933277998;922.6401703335705;930.02812061396;4935.777889994959;4944.629152813553;4958.644643794169;4971.5028497150715;4991.251312303111;4995.17579599362;5203.401608814959;5227.973860130675;5247.816326428557;5306.973465132727;5017.770646191706;5019.573336266459;5027.4721154279005;5030.924536131961;5038.924416133761;2622.3706310485727;2714.9095196698827;2753.7934654900564;2765.2395591212576;2795.7344052314997;5380.354357477127;5432.387462337501;5461.508464238158;5467.289252397163;5469.808556700496;5277.071225469901;5293.735313234323;5304.2839216706025;5306.54894333986;5332.813351532755;4995.675281106716;5404.162354835007;5410.513053825174;5418.701971391612;5435.765719396953;5141.92287115701;5223.216517522402;5256.316678831728;5268.841934741964;5290.470952904679;5092.2361645753435;5109.8978020438635;5146.459621739813;5171.224316352577;5171.3277273954145;5338.97330513345;5457.5633949283165;5660.660360378391;6132.9080063799975;6158.891399548124;4218.595400621616;4223;4225.323948168279;4235.851745188904;4240.300350442142;5677.8864422710485;5764.423557644202;5875.970620146872;5904.557158213065;5923.911141332797;5724.398938111438;5791.710414479344;5806.303243610793;5807.186993820784;5824.126381042877;5618.831435056954;5704.771809127755;5724.79963201286;5732.541396688338;5830.941690583039;5654.6324488908085;5668.412844403573;5674.687892165971;5706.885862282814;5710.942890571041;5768.625039372428;5769.403768304363;5789.652620842763;5790.870780197821;5793.131030345482;5378.9291122917175;5624.775008999594;5631.155326701028;5656.632318899258;5720 19:48:49 INFO - PROCESS | 19079 | |5;yui.html;751.5716041856144;754.4379437319199;781.4061313401813;845.961931713073;890.6838072484268;15233.695326093472;15734.921325393358;16124.355025798968;17238.22427990742;17267.30930762768;16431.342746290164;16480.83519164808;16555.337786488526;16627.08551029693;16647.50057498277;737.1633196322186;864.0840708848609;868.9044205137429;870.5212133326677;887.525174031893;879.5909901895617;881.2993670032307;882.4528792148844;887.041994645781;887.8046829697488;780.321120625057;920.8526635738283;921.2721949659795;921.2906062332;922.1331947968921;920.995395023024;928.1414691410419;928.7910220200469;944.5607792376509;945.5177859291811;681.1995904811827;683.5556888022775;685.6880119545644;686.0909295183901;686.3342557719004;16719;16777.077260750655;16779.244933978;16786.580335491588;16839.494815155565;14004.29978501071;15022.924885375503;15123.39506419753;15174;15315.081095134328;13330.600081997556;13440.462381504729;13498.32508374587;13617.727645447134;13663.453461861514;4470.463544374663;4503.5946764791;4572.222722137211;4604.424446944132;4604.654650901162;76298;81143;81293.18706812915;82200;82268.17731822724;12170.208936419282;12299.569515066929;12382.380880955916;12596.370181490889;12633.178843375152;22916.312510624706;23016.884915575316;23028.88485557595;23030.078796848273;23041.88479057594;8680.104193125853;8736.432131911406;8811.295096392401;8920.063393343735;8958.641654333884;4837.177679794443;4839.951600484021;4861.052094841472;4915.803367865316;5082.440931497532;3631.6913062389463;3776.376897811885;3782.5460944686265;3783.356829339019;3788.886333410002;629.288903538998;655.6656105386191;684.3806355248508;722.5989575785385;740.2523451314212;691.3915754136328;767.2672597669213;775.4029397363998;777.5995362388375;780.2002946979278;10239.334443261314;10487.318324309048;10587.364758114538;10601.84097238542;10650.254482186172;703.5813690854036;744.3710064995071;745.9254074592498;782.1201148707704;789.2462698123278;4678.368420263289;4732.763361831895;4835.2505361669;4884.609231261491;4888.828890988798;95229.5238523803;97264.51367743258;99229.50385248028;110795;113805;96266.51866740761;97913.51043244738;98348.50825745825;100243;100888.49555752175;87645.56177219073;87745;88050;88111;89395.10604893867;9131.360804743605;9250.907490925274;9297.349185557217;9428.669996550088;9629.277804164576;51131.23303150381;51693.48306516887;51928.22107668313;52482.47517524929;52893.20660190023;48552.271715924995;54406.72796635992;55656;73095.63452182704;77120.20437388109;13502.866571857377;13533.323333833467;13545.525906593222;13748.450061997408;13787.655308617364;14552.126872387691;14883.25583720788;14996.325165367367;14997.475088371855;15198.164100974553;10207.489625518843;12029.775289301922;12988.155769874928;13379;13959.651008724863;13162.80255796183;13364.866351336363;13748.587542373745;13918.09532380392;14172;13340.19958802475;13523.256220907944;13550.59348219555;13727.931360343533;14499.057561258485;11253.381064041228;11711.121665875264;11715.472803724024;11826.82259766087;11902.226355286879;11306.94346528262;11362;11605.071594272269;11650.825237621273;11760.941195294312 19:48:49 INFO - PROCESS | 19079 | __end_tp_report 19:48:49 INFO - PROCESS | 19079 | __start_cc_report 19:48:49 INFO - PROCESS | 19079 | _x_x_mozilla_cycle_collect,878 19:48:49 INFO - PROCESS | 19079 | __end_cc_report 19:48:49 INFO - PROCESS | 19079 | __startTimestamp1467859729285__endTimestamp 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | ------- Summary: start ------- 19:48:49 INFO - PROCESS | 19079 | Number of tests: 6 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | [#0] dojo.html Cycles:165 Average:5557.95 Median:5756.51 stddev:615.31 (10.7%) stddev-sans-first:592.33 19:48:49 INFO - PROCESS | 19079 | Values: 3350.7 3372.3 3378.2 3409.2 3426.9 5527.1 5575.8 5589.7 5590.3 5595.4 5507.1 5552.9 5553.8 5554.5 5592.2 5030.7 5053.2 5065.0 5077.6 5083.9 5056.0 5127.6 5132.1 5134.8 5137.0 5043.9 5088.3 5089.4 5107.4 5127.6 5203.9 5204.8 5207.5 5216.2 5254.2 4924.8 4988.2 5018.5 5108.5 5110.3 5398.1 5401.3 5402.0 5409.3 5450.3 6012.3 6015.4 6016.8 6023.5 6024.6 5942.0 5974.7 5991.3 6065.3 6089.7 5593.3 5612.2 5635.2 5644.7 5670.2 5914.8 6148.2 6154.3 6160.2 6163.8 5810.0 5813.9 5816.3 5936.2 5949.0 5977.3 5986.1 5989.4 6010.6 6015.6 5737.7 5830.3 5949.3 5956.9 5964.1 5871.9 5979.3 5984.9 5986.5 6025.5 4588.7 4594.3 4611.5 4613.3 4636.1 4015.0 4022.3 4037.8 4121.0 4158.4 5567.3 5589.8 5594.9 5598.9 5621.7 5945.1 5949.2 5949.8 5952.7 5975.6 5478.4 5519.0 5572.6 5597.4 5612.1 5215.3 5218.6 5218.8 5236.3 5246.2 6301.4 6314.7 6316.9 6320.8 6321.0 5824.8 5909.6 5957.9 6050.0 6061.5 6263.2 6282.0 6288.6 6291.7 6302.1 5892.4 5901.3 5909.2 5924.8 5929.7 5801.8 5922.0 5966.6 5969.3 5984.9 5872.7 5872.9 5873.4 5881.0 5883.6 5795.1 5839.7 5843.3 5853.1 5873.4 5596.0 5695.1 5698.5 5724.7 5783.4 5849.6 5944.9 5947.6 5948.6 5954.3 5569.3 5753.5 5756.5 5756.6 5757.1 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | [#1] ext.html Cycles:180 Average:65538.27 Median:225189.50 stddev:126698.62 (56.3%) stddev-sans-first:126960.17 19:48:49 INFO - PROCESS | 19079 | Values: 581.2 625.3 646.5 648.7 658.2 34231.5 37433.8 37783.2 47459.3 48113.3 50347.7 50697.0 50770.7 50822.7 50876.2 8124.6 9152.1 9300.9 9301.2 9307.7 10782.8 11021.5 11035.6 11405.4 11420.2 11294.5 11406.0 11425.1 11434.1 11438.6 8522.1 11070.8 11118.2 11140.4 11405.8 10618.4 11045.2 11056.8 11057.4 11070.3 46932.3 47436.8 47446.0 47529.8 47550.8 3671.5 3774.9 3968.4 4420.5 4431.2 36268.6 36375.6 36503.6 36816.6 36908.4 4559.3 4564.1 4567.7 4569.4 4627.9 404998.0 405393.0 406667.0 406773.0 410674.0 73091.0 73668.6 74097.6 74130.0 74284.0 40833.8 41921.8 41987.2 44542.0 44610.0 19795.7 19923.3 19980.6 20001.3 20001.4 13271.5 14803.3 14966.8 15003.3 15031.9 23158.2 23244.7 23258.7 23384.9 23413.9 7960.3 8053.9 8131.8 8190.0 8262.3 995.2 1064.7 1086.7 1104.1 1113.5 34956.1 35341.1 35557.0 35657.6 35870.6 1120.8 1149.9 1153.9 1207.8 1241.2 18205.3 18258.4 18466.5 18590.8 18626.9 587464.0 592568.0 595129.0 596982.0 597228.0 65765.0 66121.0 66755.0 66763.7 66767.0 1254.9 1297.4 1350.3 1395.0 1396.2 31431.0 35117.7 36068.3 36215.5 36281.3 55562.0 61745.7 62912.4 63088.7 70155.6 406566.0 409530.0 413110.0 413344.0 414491.0 40849.0 46579.3 47105.3 47967.8 49329.5 52713.5 52902.0 53457.5 54573.7 54674.0 43723.3 43984.3 44201.8 44619.3 44962.6 44398.6 44754.3 44774.0 44905.8 45337.3 44805.6 45505.5 46246.0 46251.5 46254.8 36878.3 39175.0 39994.8 40243.2 40471.6 29932.9 30447.5 30608.2 30729.8 30824.4 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | [#2] jquery.html Cycles:180 Average:31573.14 Median:13059.23 stddev:91497.53 (700.6%) stddev-sans-first:91724.29 19:48:49 INFO - PROCESS | 19079 | Values: 412.8 419.6 424.1 429.2 439.2 18734.7 20001.9 20657.4 21032.8 21069.6 19466.2 19499.5 19907.2 20227.9 20272.2 5587.5 5749.2 5776.9 5779.7 5780.0 4143.8 4375.8 5765.5 5806.0 6434.9 6467.3 6472.5 6501.1 6505.4 6516.3 6271.7 6475.8 6607.3 6621.9 6626.1 5833.3 5841.8 5859.8 5877.6 5891.0 20981.2 22049.7 23536.5 23763.8 23798.5 2248.4 2273.9 2354.7 2395.9 2929.1 10188.5 10376.0 10537.2 10582.7 10752.3 2020.6 2033.7 2043.6 2071.8 2078.3 84074.2 85808.0 86064.6 86530.7 86746.6 11143.3 11594.1 11975.7 11982.9 12063.4 16524.2 16713.1 16757.2 16765.7 16803.4 8704.8 8776.5 8815.5 8848.6 8939.8 8312.8 8419.2 8472.9 8553.3 8706.6 4897.3 4980.1 5115.6 5119.2 5184.3 1809.1 2006.9 2060.3 2061.3 2105.3 851.5 943.1 989.4 1055.9 1101.1 18565.5 19080.0 19670.7 19748.7 19759.6 962.6 966.1 1015.2 1035.8 1096.7 9302.5 10899.4 10974.7 11034.4 11080.7 536189.0 550545.0 556727.0 564054.0 567625.0 69739.7 101083.5 101115.0 107196.5 111618.0 1046.1 1064.3 1084.5 1095.5 1103.1 18098.8 18281.2 18424.7 18428.7 18655.3 44333.3 45178.3 45579.5 46790.1 47026.5 64093.4 64417.7 64592.7 65707.3 66222.3 8866.3 8920.6 8936.8 9147.0 9288.3 12899.0 13299.5 13450.9 13532.5 13693.9 5851.2 7681.2 8705.8 11856.6 12064.6 11114.6 11525.3 11702.9 11767.2 12066.9 11260.4 11578.9 11693.1 11792.5 12137.5 7465.1 7748.5 7764.5 8027.0 8788.1 10911.6 11184.4 11345.4 11383.7 11449.1 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | [#3] mootools.html Cycles:180 Average:6347.64 Median:4859.91 stddev:3678.17 (75.7%) stddev-sans-first:3669.81 19:48:49 INFO - PROCESS | 19079 | Values: 1415.7 1479.7 1547.8 1721.2 1912.0 9304.5 9644.1 9646.1 9938.3 9958.4 9694.2 9877.2 9880.1 9885.4 9886.4 2158.2 2448.1 2468.4 2524.5 2562.8 2503.9 2507.9 2508.2 2509.0 2511.5 2466.0 2495.5 2517.7 2519.1 2521.7 2116.8 2351.3 2421.1 2422.8 2423.3 2418.8 2468.1 2494.1 2494.9 2498.8 9630.0 9729.6 9736.7 9738.2 9748.5 5591.7 5723.7 5734.4 5734.7 5742.3 3564.9 3628.7 3667.2 3673.7 3725.9 1162.3 1186.2 1189.9 1216.2 1234.2 13662.7 13697.7 13735.3 13739.3 13749.0 4957.9 5152.2 5167.6 5220.1 5243.4 11224.6 11393.5 11438.9 11443.5 11444.7 5837.6 5848.2 5851.4 5852.9 5859.8 4762.0 5225.0 5345.3 5359.9 5454.9 4375.6 4392.5 4449.5 4493.2 4494.0 1358.4 1368.7 1372.5 1376.4 1378.6 851.3 980.6 1019.3 1059.3 1067.1 6435.4 6437.2 6519.8 6528.5 6731.5 503.7 505.4 511.5 1062.1 1088.7 4332.4 4346.5 4348.2 4389.7 4392.7 11982.6 12075.3 12141.6 12166.7 12183.0 11413.1 11831.8 12059.9 12076.9 12081.5 8702.1 8801.3 8873.6 8895.7 8919.6 6254.8 6450.7 6452.8 6494.5 6519.8 10773.8 11138.7 11195.7 11214.5 11386.0 11245.3 11271.6 11319.7 11398.8 11400.8 7212.7 7260.1 8196.1 8263.4 8365.7 9410.7 9515.3 9516.4 9534.4 9534.7 8234.3 8483.0 8486.7 8573.0 8656.4 8214.4 8312.5 8360.3 8383.9 8518.8 7540.8 7550.3 7626.7 7649.2 7650.5 5482.3 5501.7 5538.9 5753.1 5962.3 5737.9 5809.2 5855.6 5894.5 5946.5 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | [#4] prototype.html Cycles:180 Average:4661.37 Median:5221.91 stddev:1354.31 (25.9%) stddev-sans-first:1318.48 19:48:49 INFO - PROCESS | 19079 | Values: 328.3 328.6 337.4 338.0 339.8 5418.8 5420.6 5438.3 5447.7 5494.8 5350.4 5364.9 5371.2 5382.5 5411.2 4122.1 4144.3 4274.9 4277.1 4309.7 4259.4 4277.9 4280.4 4283.7 4308.4 4112.5 4249.2 4251.6 4258.8 4265.3 4155.8 4239.9 4247.4 4247.6 4255.3 3715.2 3742.3 3744.7 3756.0 3762.1 5160.5 5220.6 5223.6 5223.8 5305.5 4672.5 4908.4 4909.5 4926.2 4929.4 5758.4 5763.3 5772.1 5772.7 5791.6 4116.3 4124.7 4126.0 4128.5 4134.4 5592.6 5888.6 5942.4 5958.1 5969.6 4132.6 4143.6 4145.9 4155.1 4171.9 4182.5 4191.3 4204.0 4206.7 4213.9 4715.5 4824.2 4834.9 4875.5 4884.5 5751.0 5774.5 5792.2 5799.4 5802.6 1423.7 1438.7 1454.6 1455.3 1456.7 916.0 917.0 921.4 922.6 930.0 4935.8 4944.6 4958.6 4971.5 4991.3 4995.2 5203.4 5228.0 5247.8 5307.0 5017.8 5019.6 5027.5 5030.9 5038.9 2622.4 2714.9 2753.8 2765.2 2795.7 5380.4 5432.4 5461.5 5467.3 5469.8 5277.1 5293.7 5304.3 5306.5 5332.8 4995.7 5404.2 5410.5 5418.7 5435.8 5141.9 5223.2 5256.3 5268.8 5290.5 5092.2 5109.9 5146.5 5171.2 5171.3 5339.0 5457.6 5660.7 6132.9 6158.9 4218.6 4223.0 4225.3 4235.9 4240.3 5677.9 5764.4 5876.0 5904.6 5923.9 5724.4 5791.7 5806.3 5807.2 5824.1 5618.8 5704.8 5724.8 5732.5 5830.9 5654.6 5668.4 5674.7 5706.9 5710.9 5768.6 5769.4 5789.7 5790.9 5793.1 5378.9 5624.8 5631.2 5656.6 5720.0 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - PROCESS | 19079 | [#5] yui.html Cycles:180 Average:20659.74 Median:4784.01 stddev:28718.75 (600.3%) stddev-sans-first:28760.40 19:48:49 INFO - PROCESS | 19079 | Values: 751.6 754.4 781.4 846.0 890.7 15233.7 15734.9 16124.4 17238.2 17267.3 16431.3 16480.8 16555.3 16627.1 16647.5 737.2 864.1 868.9 870.5 887.5 879.6 881.3 882.5 887.0 887.8 780.3 920.9 921.3 921.3 922.1 921.0 928.1 928.8 944.6 945.5 681.2 683.6 685.7 686.1 686.3 16719.0 16777.1 16779.2 16786.6 16839.5 14004.3 15022.9 15123.4 15174.0 15315.1 13330.6 13440.5 13498.3 13617.7 13663.5 4470.5 4503.6 4572.2 4604.4 4604.7 76298.0 81143.0 81293.2 82200.0 82268.2 12170.2 12299.6 12382.4 12596.4 12633.2 22916.3 23016.9 23028.9 23030.1 23041.9 8680.1 8736.4 8811.3 8920.1 8958.6 4837.2 4840.0 4861.1 4915.8 5082.4 3631.7 3776.4 3782.5 3783.4 3788.9 629.3 655.7 684.4 722.6 740.3 691.4 767.3 775.4 777.6 780.2 10239.3 10487.3 10587.4 10601.8 10650.3 703.6 744.4 745.9 782.1 789.2 4678.4 4732.8 4835.3 4884.6 4888.8 95229.5 97264.5 99229.5 110795.0 113805.0 96266.5 97913.5 98348.5 100243.0 100888.5 87645.6 87745.0 88050.0 88111.0 89395.1 9131.4 9250.9 9297.3 9428.7 9629.3 51131.2 51693.5 51928.2 52482.5 52893.2 48552.3 54406.7 55656.0 73095.6 77120.2 13502.9 13533.3 13545.5 13748.5 13787.7 14552.1 14883.3 14996.3 14997.5 15198.2 10207.5 12029.8 12988.2 13379.0 13959.7 13162.8 13364.9 13748.6 13918.1 14172.0 13340.2 13523.3 13550.6 13727.9 14499.1 11253.4 11711.1 11715.5 11826.8 11902.2 11306.9 11362.0 11605.1 11650.8 11760.9 19:48:49 INFO - PROCESS | 19079 | -------- Summary: end -------- 19:48:49 INFO - PROCESS | 19079 | 19:48:49 INFO - TEST-INFO | 19079: exit 0 19:48:49 INFO - TEST-OK | dromaeo_css | took 1114526ms 19:48:49 INFO - TEST-START | kraken 19:48:49 INFO - operating with platform_type : linux_ 19:48:49 INFO - Initialising browser for kraken test... 19:48:49 INFO - TEST-INFO | started process 19270 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXKYJn1/profile http://localhost:49987/getInfo.html) 19:48:54 INFO - PROCESS | 19270 | __metrics Screen width/height:1600/1200 19:48:54 INFO - PROCESS | 19270 | colorDepth:24 19:48:54 INFO - PROCESS | 19270 | Browser inner width/height: 1024/697 19:48:54 INFO - PROCESS | 19270 | __metrics 19:48:55 ERROR - PROCESS | 19270 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:48:56 INFO - TEST-INFO | 19270: exit 0 19:48:56 INFO - Browser initialized. 19:48:56 INFO - Running cycle 1/1 for kraken test... 19:48:56 INFO - TEST-INFO | started process 19362 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXKYJn1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:49:08 INFO - PROCESS | 19362 | RSS: Main: 145879040 19:49:08 INFO - PROCESS | 19362 | 19:49:11 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49987/tests/kraken/driver.html?testName=audio-beat-detection) 19:49:11 INFO - PROCESS | 19362 | RSS: Main: 194654208 19:49:11 INFO - PROCESS | 19362 | 19:49:14 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49987/tests/kraken/driver.html?testName=audio-dft) 19:49:15 INFO - PROCESS | 19362 | RSS: Main: 191938560 19:49:15 INFO - PROCESS | 19362 | 19:49:17 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49987/tests/kraken/driver.html?testName=audio-fft) 19:49:18 INFO - PROCESS | 19362 | RSS: Main: 276697088 19:49:18 INFO - PROCESS | 19362 | 19:49:20 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49987/tests/kraken/driver.html?testName=audio-oscillator) 19:49:21 INFO - PROCESS | 19362 | RSS: Main: 185475072 19:49:21 INFO - PROCESS | 19362 | 19:49:23 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49987/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:49:23 INFO - PROCESS | 19362 | RSS: Main: 186748928 19:49:23 INFO - PROCESS | 19362 | 19:49:26 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49987/tests/kraken/driver.html?testName=imaging-darkroom) 19:49:27 INFO - PROCESS | 19362 | RSS: Main: 221892608 19:49:27 INFO - PROCESS | 19362 | 19:49:30 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49987/tests/kraken/driver.html?testName=imaging-desaturate) 19:49:31 INFO - PROCESS | 19362 | RSS: Main: 221757440 19:49:31 INFO - PROCESS | 19362 | 19:49:34 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49987/tests/kraken/driver.html?testName=json-parse-financial) 19:49:34 INFO - PROCESS | 19362 | RSS: Main: 220598272 19:49:34 INFO - PROCESS | 19362 | 19:49:36 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49987/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:49:36 INFO - PROCESS | 19362 | RSS: Main: 200806400 19:49:36 INFO - PROCESS | 19362 | 19:49:38 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:49:39 INFO - PROCESS | 19362 | RSS: Main: 201629696 19:49:39 INFO - PROCESS | 19362 | 19:49:41 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:49:41 INFO - PROCESS | 19362 | RSS: Main: 210149376 19:49:41 INFO - PROCESS | 19362 | 19:49:44 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:49:45 INFO - PROCESS | 19362 | RSS: Main: 216313856 19:49:45 INFO - PROCESS | 19362 | 19:49:47 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:49:48 INFO - PROCESS | 19362 | RSS: Main: 213798912 19:49:48 INFO - PROCESS | 19362 | 19:49:49 INFO - PROCESS | 19362 | Cycle 1(1): loaded http://localhost:49987/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49987/tests/kraken/driver.html?testName=ai-astar) 19:49:50 INFO - PROCESS | 19362 | RSS: Main: 256679936 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | __start_tp_report 19:49:50 INFO - PROCESS | 19362 | _x_x_mozilla_page_load 19:49:50 INFO - PROCESS | 19362 | _x_x_mozilla_page_load_details 19:49:50 INFO - PROCESS | 19362 | |i|pagename|runs| 19:49:50 INFO - PROCESS | 19362 | |0;ai-astar;113;104;111;109;112;110;110;111;113;110 19:49:50 INFO - PROCESS | 19362 | |1;audio-beat-detection;152;123;135;181;125;153;154;131;192;124 19:49:50 INFO - PROCESS | 19362 | |2;audio-dft;160;160;159;161;163;160;162;162;162;161 19:49:50 INFO - PROCESS | 19362 | |3;audio-fft;75;74;74;118;74;76;75;74;105;76 19:49:50 INFO - PROCESS | 19362 | |4;audio-oscillator;84;84;117;86;86;85;120;84;84;123 19:49:50 INFO - PROCESS | 19362 | |5;imaging-gaussian-blur;117;117;117;117;116;117;116;116;116;117 19:49:50 INFO - PROCESS | 19362 | |6;imaging-darkroom;145;145;145;146;145;145;145;146;145;146 19:49:50 INFO - PROCESS | 19362 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;96 19:49:50 INFO - PROCESS | 19362 | |8;json-parse-financial;50;50;55;51;52;51;51;52;53;52 19:49:50 INFO - PROCESS | 19362 | |9;json-stringify-tinderbox;59;59;59;60;59;59;59;60;59;62 19:49:50 INFO - PROCESS | 19362 | |10;stanford-crypto-aes;76;75;81;83;77;77;77;82;107;75 19:49:50 INFO - PROCESS | 19362 | |11;stanford-crypto-ccm;118;126;118;114;115;120;115;131;127;122 19:49:50 INFO - PROCESS | 19362 | |12;stanford-crypto-pbkdf2;156;154;157;155;159;163;155;153;155;155 19:49:50 INFO - PROCESS | 19362 | |13;stanford-crypto-sha256-iterative;64;65;62;62;61;62;63;61;60;59 19:49:50 INFO - PROCESS | 19362 | __end_tp_report 19:49:50 INFO - PROCESS | 19362 | __start_cc_report 19:49:50 INFO - PROCESS | 19362 | _x_x_mozilla_cycle_collect,1611 19:49:50 INFO - PROCESS | 19362 | __end_cc_report 19:49:50 INFO - PROCESS | 19362 | __startTimestamp1467859790179__endTimestamp 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | ------- Summary: start ------- 19:49:50 INFO - PROCESS | 19362 | Number of tests: 14 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.58 (2.3%) stddev-sans-first:2.55 19:49:50 INFO - PROCESS | 19362 | Values: 113.0 104.0 111.0 109.0 112.0 110.0 110.0 111.0 113.0 110.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:152.50 stddev:24.22 (15.9%) stddev-sans-first:25.62 19:49:50 INFO - PROCESS | 19362 | Values: 152.0 123.0 135.0 181.0 125.0 153.0 154.0 131.0 192.0 124.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.25 (0.8%) stddev-sans-first:1.27 19:49:50 INFO - PROCESS | 19362 | Values: 160.0 160.0 159.0 161.0 163.0 160.0 162.0 162.0 162.0 161.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#3] audio-fft Cycles:10 Average:82.10 Median:74.50 stddev:15.81 (21.2%) stddev-sans-first:16.56 19:49:50 INFO - PROCESS | 19362 | Values: 75.0 74.0 74.0 118.0 74.0 76.0 75.0 74.0 105.0 76.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:84.00 stddev:17.12 (20.4%) stddev-sans-first:17.66 19:49:50 INFO - PROCESS | 19362 | Values: 84.0 84.0 117.0 86.0 86.0 85.0 120.0 84.0 84.0 123.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:49:50 INFO - PROCESS | 19362 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 117.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:49:50 INFO - PROCESS | 19362 | Values: 145.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 146.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:49:50 INFO - PROCESS | 19362 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.49 (2.9%) stddev-sans-first:1.45 19:49:50 INFO - PROCESS | 19362 | Values: 50.0 50.0 55.0 51.0 52.0 51.0 51.0 52.0 53.0 52.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 19:49:50 INFO - PROCESS | 19362 | Values: 59.0 59.0 59.0 60.0 59.0 59.0 59.0 60.0 59.0 62.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:77.00 stddev:9.58 (12.4%) stddev-sans-first:9.99 19:49:50 INFO - PROCESS | 19362 | Values: 76.0 75.0 81.0 83.0 77.0 77.0 77.0 82.0 107.0 75.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:121.00 stddev:5.78 (4.8%) stddev-sans-first:6.05 19:49:50 INFO - PROCESS | 19362 | Values: 118.0 126.0 118.0 114.0 115.0 120.0 115.0 131.0 127.0 122.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.20 Median:155.50 stddev:2.90 (1.9%) stddev-sans-first:3.07 19:49:50 INFO - PROCESS | 19362 | Values: 156.0 154.0 157.0 155.0 159.0 163.0 155.0 153.0 155.0 155.0 19:49:50 INFO - PROCESS | 19362 | 19:49:50 INFO - PROCESS | 19362 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.79 (2.9%) stddev-sans-first:1.73 19:49:50 INFO - PROCESS | 19362 | Values: 64.0 65.0 62.0 62.0 61.0 62.0 63.0 61.0 60.0 59.0 19:49:50 INFO - PROCESS | 19362 | -------- Summary: end -------- 19:49:50 INFO - PROCESS | 19362 | 19:49:51 INFO - TEST-INFO | 19362: exit 0 19:49:51 INFO - TEST-OK | kraken | took 61186ms 19:49:51 INFO - SUITE-END | took 1175s 19:49:51 INFO - Completed test suite (00:19:35) 19:49:51 INFO - javascript benchmark 19:49:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.698437140657, 3372.258103217292, 3378.189234583699, 3409.2499650076975, 3426.8971930842067, 5527.060399732045, 5575.776968921244, 5589.7205139743, 5590.273264475622, 5595.38450770415, 5507.063799154143, 5552.888942221153, 5553.8333849984465, 5554.50009499145, 5592.245046918675, 5030.673006254591, 5053.242013697938, 5064.9746751266375, 5077.593792496591, 5083.872903177432, 5055.974720126413, 5127.615428842833, 5132.101882170605, 5134.794608215667, 5136.974315128419, 5043.899122017558, 5088.287639730441, 5089.389273287211, 5107.412647545524, 5127.589792816566, 5203.907179492311, 5204.765785539641, 5207.505286997729, 5216.191490318969, 5254.238135470336, 4924.8276310328965, 4988.176950803113, 5018.523240292203, 5108.514691104377, 5110.259012443212, 5398.13629819227, 5401.297831281908, 5401.972990135064, 5409.323834520685, 5450.264214331054, 6012.3085845127935, 6015.39846015395, 6016.849578760566, 6023.548233882432, 6024.578279520435, 5941.970290148565, 5974.731137098841, 5991.2510936132985, 6065.272167339904, 6089.725962331661, 5593.272874526286, 5612.242347283146, 5635.239242702265, 5644.6613203207935, 5670.234518340054, 5914.763409463573, 6148.1699970504305, 6154.292256390483, 6160.229971253504, 6163.84590385244, 5810.0, 5813.912791308134, 5816.272965879265, 5936.198613187251, 5948.9702551487835, 5977.282726072812, 5986.132010858401, 5989.371116032841, 6010.609310394811, 6015.578909476382, 5737.713114344266, 5830.300363956268, 5949.345571987078, 5956.851078723066, 5964.105384192319, 5871.941280587225, 5979.342272350038, 5984.850378740566, 5986.491148252448, 6025.487833534111, 4588.701734387254, 4594.287885377772, 4611.469680986663, 4613.284940834176, 4636.119137363958, 4015.0, 4022.3162062449455, 4037.7981100944835, 4120.979395103005, 4158.397032430281, 5567.2762540870235, 5589.804356847572, 5594.916076258859, 5598.860028499239, 5621.7189140542805, 5945.10823376497, 5949.196858424144, 5949.762009519657, 5952.7321270542925, 5975.641461512323, 5478.424765399655, 5518.972405137949, 5572.637778544382, 5597.412271711493, 5612.130119831432, 5215.2698622192165, 5218.63469557135, 5218.7912483500995, 5236.293100431393, 5246.160614301693, 6301.369863013662, 6314.71583778731, 6316.873662526816, 6320.778772742932, 6320.999999999908, 5824.796132135355, 5909.556783241188, 5957.880842383041, 6049.969750151221, 6061.515078793774, 6263.154474146114, 6281.96859015702, 6288.5598008138995, 6291.716872740646, 6302.054691796267, 5892.440218179266, 5901.3213480450295, 5909.231799866162, 5924.7926322578505, 5929.733162007807, 5801.767929282782, 5921.970390148022, 5966.642001479925, 5969.343372229138, 5984.880302393841, 5872.735726892215, 5872.941270587239, 5873.442023007808, 5880.970595146997, 5883.55873309512, 5795.130730390307, 5839.678817665069, 5843.328017278068, 5853.09277062056, 5873.4126587340925, 5596.0, 5695.1457281406465, 5698.515626171822, 5724.656520608777, 5783.392743761844, 5849.590528662953, 5944.851378715567, 5947.6431414115295, 5948.583599148018, 5954.3152537458745, 5569.331680198402, 5753.539716822728, 5756.510696590837, 5756.597038207286, 5757.107648314518], "value": 5518.325175395535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.2153592649923, 625.2778041362227, 646.4537465841364, 648.6951132967504, 658.2430205263943, 34231.48652770207, 37433.81283093584, 37783.24433511328, 47459.28811067837, 48113.278300825514, 50347.74826125864, 50697.0, 50770.746146269215, 50822.74588627052, 50876.23685644718, 8124.634391452399, 9152.130547597968, 9300.906990930072, 9301.209397201246, 9307.720768376927, 10782.78434431311, 11021.504032318566, 11035.558577656884, 11405.37270450125, 11420.200585958986, 11294.548218071268, 11406.0, 11425.143114266422, 11434.085273178167, 11438.599649012287, 8522.062573116953, 11070.833937490945, 11118.22172447929, 11140.387278699669, 11405.771884562262, 10618.36289822613, 11045.171612129123, 11056.834147487794, 11057.447127643585, 11070.28043177199, 46932.2960155598, 47436.76281618604, 47446.0, 47529.76235118837, 47550.7622461889, 3671.467637192619, 3774.924501509982, 3968.4047392891007, 4420.491643461011, 4431.2466880630045, 36268.63731362679, 36375.63624363749, 36503.634963650285, 36816.63183368159, 36908.446373304425, 4559.293309537027, 4564.087182563468, 4567.703099298535, 4569.430569430569, 4627.930581041287, 404998.0, 405393.0, 406666.99999999703, 406773.0, 410674.0, 73091.0, 73668.63165684245, 74097.6295118521, 74130.0, 74284.0, 40833.79583102065, 41921.79039104785, 41987.16025679469, 44542.0, 44610.0, 19795.703064454046, 19923.302684405975, 19980.60038799216, 20001.299954501523, 20001.399958001282, 13271.535496257584, 14803.333849976776, 14966.775498367533, 15003.32485038176, 15031.924840375728, 23158.1894633687, 23244.65133023006, 23258.65112023321, 23384.883075584854, 23413.88293058558, 7960.303420981649, 8053.919460805434, 8131.83736325282, 8190.0, 8262.339012878956, 995.2088089968493, 1064.6593090211213, 1086.7065892209218, 1104.0670633314837, 1113.5156207049959, 34956.12609684778, 35341.11647208789, 35557.0, 35657.64342356543, 35870.64129358725, 1120.7982563138714, 1149.8620165580016, 1153.8903804138592, 1207.8429804125408, 1241.205628397826, 18205.27178912855, 18258.36095736643, 18466.538336541693, 18590.814091859178, 18626.90686546586, 587464.0, 592568.0, 595129.0, 596982.0, 597228.0, 65765.0, 66121.0, 66755.0, 66763.66618166878, 66767.0, 1254.930978796175, 1297.4356155072385, 1350.3180893648841, 1394.986050139506, 1396.2250950722243, 31431.0, 35117.65116952342, 36068.27863442664, 36215.456768147975, 36281.274374511835, 55562.0, 61745.69127154336, 62912.37087629248, 63088.684556576925, 70155.64922175561, 406566.0, 409530.0, 413110.0, 413344.0, 414491.0, 40849.0, 46579.301310481045, 47105.293420598035, 47967.760161200364, 49329.50670493249, 52713.47286527239, 52902.0, 53457.465425345246, 54573.72713136409, 54674.0, 43723.34414983714, 43984.34023489586, 44201.77899110484, 44619.33071004003, 44962.55037449583, 44398.59747962934, 44754.3286850704, 44774.0, 44905.77547112243, 45337.319940200265, 44805.55194448014, 45505.54494455013, 46246.0, 46251.53748462472, 46254.76872615615, 36878.26243475062, 39175.0, 39994.800025999684, 40243.195136096525, 40471.59528404678, 29932.850335748182, 30447.543286850272, 30608.234794130323, 30729.846350768104, 30824.383512330078], "value": 22435.878363684005, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.78122595024627, 419.6349176216691, 424.09032625019313, 429.17812389274553, 439.1721604774998, 18734.718979215322, 20001.899990500046, 20657.38027859165, 21032.789672103314, 21069.578608427822, 19466.221351145938, 19499.5125121872, 19907.203711851507, 20227.898860505677, 20272.18911243552, 5587.497125258726, 5749.166370876221, 5776.913346299809, 5779.682117483537, 5780.0, 4143.751374917515, 4375.824967001332, 5765.509931655814, 5806.0, 6434.903476447857, 6467.256265529454, 6472.546921715481, 6501.057346684728, 6505.4145126938565, 6516.348365163493, 6271.749130034793, 6475.805725828209, 6607.3392660734025, 6621.933780662204, 6626.072349871022, 5833.300003999505, 5841.766329346821, 5859.7949071782705, 5877.5591830612875, 5891.027980383232, 20981.16075356984, 22049.669254961187, 23536.52926941469, 23763.76236237633, 23798.524029519485, 2248.3816950338623, 2273.8521996070367, 2354.693889794333, 2395.916142934989, 2929.062699936, 10188.541515631814, 10376.0, 10537.157027437786, 10582.735431614194, 10752.301100428525, 2020.5754942765316, 2033.7051127586565, 2043.6423625865564, 2071.8238949689294, 2078.324544523036, 84074.15925840725, 85808.0, 86064.56967715122, 86530.70203946883, 86746.56626716827, 11143.275687080313, 11594.130440217017, 11975.700607484883, 11982.88017119835, 12063.396830158457, 16524.173791310386, 16713.080780556942, 16757.245923933453, 16765.664686706197, 16803.41188058412, 8704.782380440412, 8776.517291549026, 8815.515146666867, 8848.557572121368, 8939.776505587282, 8312.792180195544, 8419.200175983271, 8472.872906906401, 8553.315734741205, 8706.564671766513, 4897.314375987365, 4980.12847751642, 5115.590752739772, 5119.1553393690365, 5184.300119483897, 1809.1499157335188, 2006.8695534790193, 2060.258307009475, 2061.2682497713395, 2105.347342323854, 851.5061264466594, 943.094629156018, 989.4360214677708, 1055.904968552834, 1101.0695961912172, 18565.535861603297, 19080.0, 19670.70493942592, 19748.716333438282, 19759.60480790405, 962.6149540183842, 966.0725703324887, 1015.21828192293, 1035.7669524356959, 1096.7203363142348, 9302.53487325631, 10899.400532970603, 10974.725631859268, 11034.448277586089, 11080.722981925517, 536189.0, 550545.0, 556727.0, 564054.0, 567625.0, 69739.65130174317, 101083.49458252662, 101115.0, 107196.4640176794, 111618.0, 1046.1421634259834, 1064.2656566968767, 1084.4577711144427, 1095.4632230207142, 1103.1064837481827, 18098.81901180971, 18281.17734701915, 18424.723629145843, 18428.723569146743, 18655.253789848255, 44333.33499997438, 45178.32232516449, 45579.54420455886, 46790.064198716515, 47026.52973470358, 64093.3590664106, 64417.67791161014, 64592.677036614514, 65707.34292657013, 66222.33777662161, 8866.335024873031, 8920.598573064357, 8936.776580585538, 9146.95426522863, 9288.34981551285, 12899.0, 13299.46802127904, 13450.932745336211, 13532.526361577691, 13693.931530342285, 5851.21008663825, 7681.231876812498, 8705.782355441164, 11856.58501952462, 12064.577739779066, 11114.610988615359, 11525.250858694493, 11702.941485292518, 11767.23512971654, 12066.879331206927, 11260.436978151223, 11578.94210528942, 11693.12301577368, 11792.469338879942, 12137.514499419924, 7465.104187497535, 7748.496347737379, 7764.456488045783, 8027.0, 8788.077251888715, 10911.563537458413, 11184.384858832842, 11345.432728363714, 11383.715407114889, 11449.14131440162], "value": 9536.034532358104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.6673181802269, 1479.6596782739969, 1547.8208836142414, 1721.1996421663932, 1912.0344226165794, 9304.488253146073, 9644.083812037854, 9646.083622055894, 9938.254630902675, 9958.352707074035, 9694.224462043037, 9877.160441362494, 9880.061394167542, 9885.40687558747, 9886.40681559107, 2158.1583182558816, 2448.1309135256993, 2468.395243165427, 2524.507720994407, 2562.8334158279745, 2503.8622875741826, 2507.874606269679, 2508.234988328559, 2509.0, 2511.5353659572947, 2465.9876700616473, 2495.463475352799, 2517.748225177486, 2519.067944860404, 2521.6595759572497, 2116.75657299411, 2351.341624345186, 2421.1041914491575, 2422.8061755059553, 2423.273018094573, 2418.7944024757917, 2468.136152346682, 2494.1270555305678, 2494.887730052134, 2498.812589055828, 9630.0, 9729.56216970238, 9736.707898763048, 9738.172255358302, 9748.512574371252, 5591.720413979285, 5723.71381430931, 5734.369219385864, 5734.741936612862, 5742.310922689304, 3564.8752293669595, 3628.6915612172997, 3667.1932174921476, 3673.687736542397, 3725.9068523286865, 1162.3316592958997, 1186.1696812231387, 1189.8988585970117, 1216.1912328301669, 1234.2347744398442, 13662.658433539242, 13697.726045479134, 13735.313234338244, 13739.313034348243, 13749.0, 4957.876053098629, 5152.175651895679, 5167.638265321467, 5220.146847869028, 5243.449437809051, 11224.607138750105, 11393.487293071832, 11438.942805286088, 11443.54225830974, 11444.656660300205, 5837.591368604157, 5848.152017957373, 5851.444112809277, 5852.912206316909, 5859.765609375662, 4761.95238047622, 5224.9738751306, 5345.251664766935, 5359.892802144013, 5454.863628409322, 4375.584319489643, 4392.538783427758, 4449.510553839075, 4493.168763778712, 4494.0, 1358.3547814788094, 1368.705728268427, 1372.457879137735, 1376.4012654495327, 1378.6484446466086, 851.3274513134554, 980.645932061644, 1019.3017782818793, 1059.327327147267, 1067.071647666535, 6435.388638079375, 6437.163168788121, 6519.837004074936, 6528.477721782246, 6731.49513786468, 503.7204351584832, 505.3985756949219, 511.49617626637735, 1062.1396668698223, 1088.6570730219955, 4332.393464914914, 4346.47842258931, 4348.2390581648715, 4389.6927215095275, 4392.670549708785, 11982.64052078439, 12075.27548347102, 12141.635750927486, 12166.695832604255, 12183.0, 11413.086953043736, 11831.822522662167, 12059.879401206052, 12076.939615301868, 12081.45633446497, 8702.086280940537, 8801.295896328278, 8873.556322183995, 8895.688650897187, 8919.643214271486, 6254.8123556293385, 6450.709718062604, 6452.806415807533, 6494.512911531749, 6519.804405867641, 10773.784524309627, 11138.665840024812, 11195.72010699739, 11214.495347709535, 11386.0, 11245.325280483197, 11271.605493807678, 11319.660410187707, 11398.772024559628, 11400.828987565028, 7212.675429605785, 7260.092488438945, 8196.057453392936, 8263.380246481658, 8365.707200247962, 9410.670626528039, 9515.33392662507, 9516.429014259167, 9534.427934323963, 9534.713958580976, 8234.341252699889, 8482.957585212034, 8486.660533578835, 8572.957135214285, 8656.350773691873, 8214.424990250864, 8312.501249925024, 8360.28937540316, 8383.916160838313, 8518.829623407373, 7540.849183016199, 7550.282723141293, 7626.7034604116325, 7649.196834332315, 7650.540967541965, 5482.314710661168, 5501.724913754377, 5538.86152846166, 5753.05074662676, 5962.284525856925, 5737.9139312911175, 5809.24479817621, 5855.59010869235, 5894.469497745052, 5946.524278057832], "value": 4997.371117339881, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.2515863830466, 328.5859816631045, 337.41626985315395, 337.9645137260588, 339.79612232660423, 5418.807862270304, 5420.5934554908345, 5438.293021907151, 5447.700376479297, 5494.835154945358, 5350.411454739985, 5364.892702145955, 5371.248025276464, 5382.542483888874, 5411.161270003156, 4122.113745544707, 4144.254034273829, 4274.893127671802, 4277.118068050064, 4309.698321117523, 4259.446271984638, 4277.850275240352, 4280.352064455192, 4283.67872409569, 4308.396824444565, 4112.527059388164, 4249.213895429355, 4251.574842515739, 4258.829646814123, 4265.31754919213, 4155.815592556122, 4239.894002649928, 4247.362895565674, 4247.575242475758, 4255.27660297747, 3715.1826598148364, 3742.2515496900455, 3744.6782994543555, 3755.9858838113555, 3762.0782908187393, 5160.458151894035, 5220.608454365938, 5223.6343455958095, 5223.843284701465, 5305.5409762315285, 4672.509386514401, 4908.43552991407, 4909.459959404463, 4926.236433352836, 4929.383827021622, 5758.42415758425, 5763.337216220147, 5772.076467765138, 5772.682502462363, 5791.594588378815, 4116.300228961084, 4124.6906482013665, 4125.97937010316, 4128.4632997710405, 4134.37984302357, 5592.6085174037835, 5888.5877988540815, 5942.435468630516, 5958.136070269787, 5969.641821490724, 4132.607402296777, 4143.56492568282, 4145.8963525912095, 4155.148194620096, 4171.937420938689, 4182.498100227992, 4191.296613684157, 4204.012057166564, 4206.747595144301, 4213.894652633709, 4715.481297057321, 4824.228123500293, 4834.927476087862, 4875.464228767927, 4884.462709101996, 5751.0, 5774.48029677331, 5792.218050563204, 5799.376174671099, 5802.622829516068, 1423.7223741370333, 1438.7050654615778, 1454.6436123149685, 1455.316001479303, 1456.730504856605, 916.0198587511433, 916.977075573116, 921.4056933277998, 922.6401703335705, 930.02812061396, 4935.777889994959, 4944.629152813553, 4958.644643794169, 4971.5028497150715, 4991.251312303111, 4995.17579599362, 5203.401608814959, 5227.973860130675, 5247.816326428557, 5306.973465132727, 5017.770646191706, 5019.573336266459, 5027.4721154279005, 5030.924536131961, 5038.924416133761, 2622.3706310485727, 2714.9095196698827, 2753.7934654900564, 2765.2395591212576, 2795.7344052314997, 5380.354357477127, 5432.387462337501, 5461.508464238158, 5467.289252397163, 5469.808556700496, 5277.071225469901, 5293.735313234323, 5304.2839216706025, 5306.54894333986, 5332.813351532755, 4995.675281106716, 5404.162354835007, 5410.513053825174, 5418.701971391612, 5435.765719396953, 5141.92287115701, 5223.216517522402, 5256.316678831728, 5268.841934741964, 5290.470952904679, 5092.2361645753435, 5109.8978020438635, 5146.459621739813, 5171.224316352577, 5171.3277273954145, 5338.97330513345, 5457.5633949283165, 5660.660360378391, 6132.9080063799975, 6158.891399548124, 4218.595400621616, 4223.0, 4225.323948168279, 4235.851745188904, 4240.300350442142, 5677.8864422710485, 5764.423557644202, 5875.970620146872, 5904.557158213065, 5923.911141332797, 5724.398938111438, 5791.710414479344, 5806.303243610793, 5807.186993820784, 5824.126381042877, 5618.831435056954, 5704.771809127755, 5724.79963201286, 5732.541396688338, 5830.941690583039, 5654.6324488908085, 5668.412844403573, 5674.687892165971, 5706.885862282814, 5710.942890571041, 5768.625039372428, 5769.403768304363, 5789.652620842763, 5790.870780197821, 5793.131030345482, 5378.9291122917175, 5624.775008999594, 5631.155326701028, 5656.632318899258, 5720.0], "value": 4227.965362793832, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.5716041856144, 754.4379437319199, 781.4061313401813, 845.961931713073, 890.6838072484268, 15233.695326093472, 15734.921325393358, 16124.355025798968, 17238.22427990742, 17267.30930762768, 16431.342746290164, 16480.83519164808, 16555.337786488526, 16627.08551029693, 16647.50057498277, 737.1633196322186, 864.0840708848609, 868.9044205137429, 870.5212133326677, 887.525174031893, 879.5909901895617, 881.2993670032307, 882.4528792148844, 887.041994645781, 887.8046829697488, 780.321120625057, 920.8526635738283, 921.2721949659795, 921.2906062332, 922.1331947968921, 920.995395023024, 928.1414691410419, 928.7910220200469, 944.5607792376509, 945.5177859291811, 681.1995904811827, 683.5556888022775, 685.6880119545644, 686.0909295183901, 686.3342557719004, 16719.0, 16777.077260750655, 16779.244933978, 16786.580335491588, 16839.494815155565, 14004.29978501071, 15022.924885375503, 15123.39506419753, 15174.0, 15315.081095134328, 13330.600081997556, 13440.462381504729, 13498.32508374587, 13617.727645447134, 13663.453461861514, 4470.463544374663, 4503.5946764791, 4572.222722137211, 4604.424446944132, 4604.654650901162, 76298.0, 81143.0, 81293.18706812915, 82200.0, 82268.17731822724, 12170.208936419282, 12299.569515066929, 12382.380880955916, 12596.370181490889, 12633.178843375152, 22916.312510624706, 23016.884915575316, 23028.88485557595, 23030.078796848273, 23041.88479057594, 8680.104193125853, 8736.432131911406, 8811.295096392401, 8920.063393343735, 8958.641654333884, 4837.177679794443, 4839.951600484021, 4861.052094841472, 4915.803367865316, 5082.440931497532, 3631.6913062389463, 3776.376897811885, 3782.5460944686265, 3783.356829339019, 3788.886333410002, 629.288903538998, 655.6656105386191, 684.3806355248508, 722.5989575785385, 740.2523451314212, 691.3915754136328, 767.2672597669213, 775.4029397363998, 777.5995362388375, 780.2002946979278, 10239.334443261314, 10487.318324309048, 10587.364758114538, 10601.84097238542, 10650.254482186172, 703.5813690854036, 744.3710064995071, 745.9254074592498, 782.1201148707704, 789.2462698123278, 4678.368420263289, 4732.763361831895, 4835.2505361669, 4884.609231261491, 4888.828890988798, 95229.5238523803, 97264.51367743258, 99229.50385248028, 110795.0, 113805.0, 96266.51866740761, 97913.51043244738, 98348.50825745825, 100243.0, 100888.49555752175, 87645.56177219073, 87745.0, 88050.0, 88111.0, 89395.10604893867, 9131.360804743605, 9250.907490925274, 9297.349185557217, 9428.669996550088, 9629.277804164576, 51131.23303150381, 51693.48306516887, 51928.22107668313, 52482.47517524929, 52893.20660190023, 48552.271715924995, 54406.72796635992, 55656.0, 73095.63452182704, 77120.20437388109, 13502.866571857377, 13533.323333833467, 13545.525906593222, 13748.450061997408, 13787.655308617364, 14552.126872387691, 14883.25583720788, 14996.325165367367, 14997.475088371855, 15198.164100974553, 10207.489625518843, 12029.775289301922, 12988.155769874928, 13379.0, 13959.651008724863, 13162.80255796183, 13364.866351336363, 13748.587542373745, 13918.09532380392, 14172.0, 13340.19958802475, 13523.256220907944, 13550.59348219555, 13727.931360343533, 14499.057561258485, 11253.381064041228, 11711.121665875264, 11715.472803724024, 11826.82259766087, 11902.226355286879, 11306.94346528262, 11362.0, 11605.071594272269, 11650.825237621273, 11760.941195294312], "value": 7876.770127542975, "unit": "score"}], "name": "dromaeo_css", "value": 7624.844585526186, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 104.0, 111.0, 109.0, 112.0, 110.0, 110.0, 111.0, 113.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 135.0, 181.0, 125.0, 153.0, 154.0, 131.0, 192.0, 124.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 161.0, 163.0, 160.0, 162.0, 162.0, 162.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 118.0, 74.0, 76.0, 75.0, 74.0, 105.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 117.0, 86.0, 86.0, 85.0, 120.0, 84.0, 84.0, 123.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 55.0, 51.0, 52.0, 51.0, 51.0, 52.0, 53.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 59.0, 62.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 81.0, 83.0, 77.0, 77.0, 77.0, 82.0, 107.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 118.0, 114.0, 115.0, 120.0, 115.0, 131.0, 127.0, 122.0], "value": 120.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 157.0, 155.0, 159.0, 163.0, 155.0, 153.0, 155.0, 155.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 62.0, 62.0, 61.0, 62.0, 63.0, 61.0, 60.0, 59.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1483.6000000000001, "alertThreshold": 2.0}]} 19:49:51 INFO - Return code: 0 19:49:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:49:51 ERROR - # TBPL SUCCESS # 19:49:51 INFO - Running post-action listener: _resource_record_post_action 19:49:51 INFO - ##### 19:49:51 INFO - ##### Finished run-tests step (success) 19:49:51 INFO - ##### 19:49:51 INFO - Running post-run listener: _resource_record_post_run 19:49:51 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 504889344; Read time: 0; Write time: 1057736 19:49:51 INFO - TinderboxPrint: CPU usage
13.2% 19:49:51 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:49:51 INFO - TinderboxPrint: I/O write bytes / time
504,889,344 / 1,057,736 19:49:51 INFO - TinderboxPrint: CPU idle
8,263.9 (86.8%) 19:49:51 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 19:49:51 INFO - TinderboxPrint: Swap in / out
0 / 0 19:49:51 INFO - install - Wall time: 17s; CPU: 18.0%; Read bytes: 0; Write bytes: 253689856; Read time: 0; Write time: 753280 19:49:51 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 251199488; Read time: 0; Write time: 304456 19:49:52 INFO - Running post-run listener: _upload_blobber_files 19:49:52 INFO - Blob upload gear active. 19:49:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:49:52 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 19:49:52 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'] 19:49:52 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 19:49:52 INFO - (blobuploader) - INFO - Open directory for files ... 19:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:49:55 INFO - (blobuploader) - INFO - Done attempting. 19:49:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:49:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:49:56 INFO - (blobuploader) - INFO - Done attempting. 19:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:49:57 INFO - (blobuploader) - INFO - Done attempting. 19:49:57 INFO - (blobuploader) - INFO - Iteration through files over. 19:49:57 INFO - Return code: 0 19:49:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:49:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:49:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe55ae44b05e6bb2c3732173c3bfe2bd0db4caf7dbe4afa28eb7059e0c2f9e06fad0033ae2da3713bb8bef40c630300a3e9f582b61a2730ae795c133d0a378c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43d38cd43916da58c2f298b1dde433cf8505bbe5720ebcd8fbfa5f28fedc24d7f2acb1d28007eabbb936b50f8d6176d92e6d949337fa669895d8eb9b7fec0593", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a16650c717a22b2f45586adbe3731aa08d35a3cad19d04cb49af491d31b0bbe080a281e33167108e2d865624a32806a1fa26384d1872d47f4ae56598e3e2bf"} 19:49:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:49:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:49:57 INFO - Contents: 19:49:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe55ae44b05e6bb2c3732173c3bfe2bd0db4caf7dbe4afa28eb7059e0c2f9e06fad0033ae2da3713bb8bef40c630300a3e9f582b61a2730ae795c133d0a378c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43d38cd43916da58c2f298b1dde433cf8505bbe5720ebcd8fbfa5f28fedc24d7f2acb1d28007eabbb936b50f8d6176d92e6d949337fa669895d8eb9b7fec0593", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a16650c717a22b2f45586adbe3731aa08d35a3cad19d04cb49af491d31b0bbe080a281e33167108e2d865624a32806a1fa26384d1872d47f4ae56598e3e2bf"} 19:49:57 INFO - Running post-run listener: copy_logs_to_upload_dir 19:49:57 INFO - Copying logs to upload dir... 19:49:57 INFO - mkdir: /builds/slave/test/build/upload/logs 19:49:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.385001 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-06 19:49:56.846259) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 19:49:56.849944) ========= 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-1467855093.274074-1619602952 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/fe55ae44b05e6bb2c3732173c3bfe2bd0db4caf7dbe4afa28eb7059e0c2f9e06fad0033ae2da3713bb8bef40c630300a3e9f582b61a2730ae795c133d0a378c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43d38cd43916da58c2f298b1dde433cf8505bbe5720ebcd8fbfa5f28fedc24d7f2acb1d28007eabbb936b50f8d6176d92e6d949337fa669895d8eb9b7fec0593", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a16650c717a22b2f45586adbe3731aa08d35a3cad19d04cb49af491d31b0bbe080a281e33167108e2d865624a32806a1fa26384d1872d47f4ae56598e3e2bf"} build_url:https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/EdJhTECcTwqoMOLmYmsnKg/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/fe55ae44b05e6bb2c3732173c3bfe2bd0db4caf7dbe4afa28eb7059e0c2f9e06fad0033ae2da3713bb8bef40c630300a3e9f582b61a2730ae795c133d0a378c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43d38cd43916da58c2f298b1dde433cf8505bbe5720ebcd8fbfa5f28fedc24d7f2acb1d28007eabbb936b50f8d6176d92e6d949337fa669895d8eb9b7fec0593", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a16650c717a22b2f45586adbe3731aa08d35a3cad19d04cb49af491d31b0bbe080a281e33167108e2d865624a32806a1fa26384d1872d47f4ae56598e3e2bf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 19:49:56.876102) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:49:56.876426) ========= 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-1467855093.274074-1619602952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 19:49:56.958282) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 19:49:56.958777) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 19:49:56.959391) ========= ========= Total master_lag: 0.17 =========