builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1469877434.97 results: success (0) buildid: 20160729220924 builduid: 55f0f713cef1469489ac756a477dd212 revision: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:14.971302) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:14.971768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:14.972091) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:14.987426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:14.988107) ========= 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=4300f881736b422476174874000001bc-1469877434.507722-2103265578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 04:17:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2016-07-30 04:17:15 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.377592 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:15.385108) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:15.385838) ========= 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=4300f881736b422476174874000001bc-1469877434.507722-2103265578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020056 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:15.438386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:15.439328) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 --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=4300f881736b422476174874000001bc-1469877434.507722-2103265578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 04:17:15,721 truncating revision to first 12 chars 2016-07-30 04:17:15,721 Setting DEBUG logging. 2016-07-30 04:17:15,721 attempt 1/10 2016-07-30 04:17:15,721 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cf46248f894f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 04:17:16,113 unpacking tar archive at: autoland-cf46248f894f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.726079 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:16.184699) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:16.185507) ========= script_repo_revision: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:16.186372) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:16.186926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:16.198524) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-30 04:17:16.199233) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=4300f881736b422476174874000001bc-1469877434.507722-2103265578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:17:16 INFO - MultiFileLogger online at 20160730 04:17:16 in /builds/slave/test 04:17:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 04:17:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:17:16 INFO - {'append_to_log': False, 04:17:16 INFO - 'base_work_dir': '/builds/slave/test', 04:17:16 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 04:17:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:17:16 INFO - 'branch': 'Autoland-Non-PGO', 04:17:16 INFO - 'buildbot_json_path': 'buildprops.json', 04:17:16 INFO - 'config_files': ('talos/linux_config.py',), 04:17:16 INFO - 'default_actions': ('clobber', 04:17:16 INFO - 'read-buildbot-config', 04:17:16 INFO - 'download-and-extract', 04:17:16 INFO - 'populate-webroot', 04:17:16 INFO - 'create-virtualenv', 04:17:16 INFO - 'install', 04:17:16 INFO - 'run-tests'), 04:17:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:17:16 INFO - 'download_minidump_stackwalk': True, 04:17:16 INFO - 'download_symbols': 'ondemand', 04:17:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:17:16 INFO - 'tooltool.py': '/tools/tooltool.py', 04:17:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:17:16 INFO - '/tools/misc-python/virtualenv.py')}, 04:17:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:17:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:17:16 INFO - 'installer_path': 'installer.exe', 04:17:16 INFO - 'log_level': 'info', 04:17:16 INFO - 'log_name': 'talos', 04:17:16 INFO - 'log_to_console': True, 04:17:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:17:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:17:16 INFO - 'opt_config_files': (), 04:17:16 INFO - 'pip_index': False, 04:17:16 INFO - 'sps_profile': False, 04:17:16 INFO - 'sps_profile_interval': 0, 04:17:16 INFO - 'suite': 'dromaeojs', 04:17:16 INFO - 'system_bits': '32', 04:17:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:17:16 INFO - 'title': 'talos-linux64-ix-045', 04:17:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:17:16 INFO - 'use_talos_json': True, 04:17:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:17:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:17:16 INFO - 'work_dir': 'build'} 04:17:16 INFO - [mozharness: 2016-07-30 11:17:16.576500Z] Running clobber step. 04:17:16 INFO - Running pre-action listener: _resource_record_pre_action 04:17:16 INFO - Running main action method: clobber 04:17:16 INFO - rmtree: /builds/slave/test/build 04:17:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:17:17 INFO - Running post-action listener: _resource_record_post_action 04:17:17 INFO - [mozharness: 2016-07-30 11:17:17.055800Z] Finished clobber step (success) 04:17:17 INFO - [mozharness: 2016-07-30 11:17:17.055904Z] Running read-buildbot-config step. 04:17:17 INFO - Running pre-action listener: _resource_record_pre_action 04:17:17 INFO - Running main action method: read_buildbot_config 04:17:17 INFO - Using buildbot properties: 04:17:17 INFO - { 04:17:17 INFO - "project": "", 04:17:17 INFO - "product": "firefox", 04:17:17 INFO - "script_repo_revision": "production", 04:17:17 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 04:17:17 INFO - "repository": "", 04:17:17 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 04:17:17 INFO - "buildid": "20160729220924", 04:17:17 INFO - "pgo_build": "False", 04:17:17 INFO - "basedir": "/builds/slave/test", 04:17:17 INFO - "buildnumber": 271, 04:17:17 INFO - "slavename": "talos-linux64-ix-045", 04:17:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:17:17 INFO - "platform": "ubuntu64_hw", 04:17:17 INFO - "scheduler": "tests-autoland-linux64-talos", 04:17:17 INFO - "branch": "autoland", 04:17:17 INFO - "repo_path": "integration/autoland", 04:17:17 INFO - "revision": "cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8", 04:17:17 INFO - "stage_platform": "linux64", 04:17:17 INFO - "builduid": "55f0f713cef1469489ac756a477dd212", 04:17:17 INFO - "slavebuilddir": "test" 04:17:17 INFO - } 04:17:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:17:17 INFO - Running post-action listener: _resource_record_post_action 04:17:17 INFO - [mozharness: 2016-07-30 11:17:17.058396Z] Finished read-buildbot-config step (success) 04:17:17 INFO - [mozharness: 2016-07-30 11:17:17.058486Z] Running download-and-extract step. 04:17:17 INFO - Running pre-action listener: _resource_record_pre_action 04:17:17 INFO - Running main action method: download_and_extract 04:17:17 INFO - mkdir: /builds/slave/test/build/tests 04:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:17 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:17:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:17:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:17:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:17:17 INFO - Downloaded 1361 bytes. 04:17:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:17:17 INFO - Using the following test package requirements: 04:17:17 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:17:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:17:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:17:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'jsshell-linux-x86_64.zip'], 04:17:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:17:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:17:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:17:17 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:17:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:17:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:17:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:17 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:17:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:17:19 INFO - Downloaded 22760926 bytes. 04:17:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:17:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:17:20 INFO - Return code: 0 04:17:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:20 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:17:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:17:21 INFO - Downloaded 22760926 bytes. 04:17:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:17:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:17:22 INFO - Return code: 0 04:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:22 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:17:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:17:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:17:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:17:23 INFO - Downloaded 14413725 bytes. 04:17:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:17:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:17:24 INFO - Return code: 0 04:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:24 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:17:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:17:26 INFO - Downloaded 57172742 bytes. 04:17:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:26 INFO - mkdir: /builds/slave/test/properties 04:17:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:17:26 INFO - Writing to file /builds/slave/test/properties/build_url 04:17:26 INFO - Contents: 04:17:26 INFO - build_url:https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:26 INFO - Running post-action listener: _resource_record_post_action 04:17:26 INFO - Running post-action listener: set_extra_try_arguments 04:17:26 INFO - [mozharness: 2016-07-30 11:17:26.922652Z] Finished download-and-extract step (success) 04:17:26 INFO - [mozharness: 2016-07-30 11:17:26.922976Z] Running populate-webroot step. 04:17:26 INFO - Running pre-action listener: _resource_record_pre_action 04:17:26 INFO - Running main action method: populate_webroot 04:17:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:17:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:17:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:17:26 INFO - '--tppagecycles', 04:17:26 INFO - '7'], 04:17:26 INFO - 'tests': ['tsvgm']}}, 04:17:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:17:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:17:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:17:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:17:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['damp', 'tps']}, 04:17:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'tests': ['damp', 'tps']}, 04:17:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['dromaeo_dom']}, 04:17:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:17:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['basic_compositor_video']}, 04:17:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:17:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['a11yr', 04:17:26 INFO - 'ts_paint', 04:17:26 INFO - 'tpaint', 04:17:26 INFO - 'sessionrestore', 04:17:26 INFO - 'sessionrestore_no_auto_restore', 04:17:26 INFO - 'tabpaint']}, 04:17:26 INFO - 'other-e10s': {'tests': ['a11yr', 04:17:26 INFO - 'ts_paint', 04:17:26 INFO - 'tpaint', 04:17:26 INFO - 'sessionrestore', 04:17:26 INFO - 'sessionrestore_no_auto_restore', 04:17:26 INFO - 'tabpaint']}, 04:17:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:17:26 INFO - 'ts_paint', 04:17:26 INFO - 'tpaint', 04:17:26 INFO - 'sessionrestore', 04:17:26 INFO - 'sessionrestore_no_auto_restore', 04:17:26 INFO - 'tabpaint']}, 04:17:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:17:26 INFO - 'ts_paint', 04:17:26 INFO - 'tpaint', 04:17:26 INFO - 'sessionrestore', 04:17:26 INFO - 'sessionrestore_no_auto_restore', 04:17:26 INFO - 'tabpaint']}, 04:17:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['a11yr', 04:17:26 INFO - 'ts_paint', 04:17:26 INFO - 'tpaint', 04:17:26 INFO - 'sessionrestore', 04:17:26 INFO - 'sessionrestore_no_auto_restore', 04:17:26 INFO - 'tabpaint']}, 04:17:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['a11yr', 04:17:26 INFO - 'ts_paint', 04:17:26 INFO - 'tpaint', 04:17:26 INFO - 'sessionrestore', 04:17:26 INFO - 'sessionrestore_no_auto_restore', 04:17:26 INFO - 'tabpaint']}, 04:17:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['tsvgx', 04:17:26 INFO - 'tsvgr_opacity', 04:17:26 INFO - 'tart', 04:17:26 INFO - 'tscrollx', 04:17:26 INFO - 'cart']}, 04:17:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:17:26 INFO - 'tsvgr_opacity', 04:17:26 INFO - 'tart', 04:17:26 INFO - 'tscrollx', 04:17:26 INFO - 'cart']}, 04:17:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'talos_options': ['--disable-e10s'], 04:17:26 INFO - 'tests': ['tp5o']}, 04:17:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'tests': ['tp5o']}, 04:17:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'talos_options': ['--disable-e10s', 04:17:26 INFO - '--xperf_path', 04:17:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:26 INFO - 'tests': ['tp5n']}, 04:17:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:26 INFO - 'talos_options': ['--xperf_path', 04:17:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:26 INFO - 'tests': ['tp5n']}}, 04:17:26 INFO - 'talos.zip': {'path': '', 04:17:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:17:26 INFO - Running post-action listener: _resource_record_post_action 04:17:26 INFO - [mozharness: 2016-07-30 11:17:26.940950Z] Finished populate-webroot step (success) 04:17:26 INFO - [mozharness: 2016-07-30 11:17:26.941039Z] Running create-virtualenv step. 04:17:26 INFO - Running pre-action listener: _resource_record_pre_action 04:17:26 INFO - Running main action method: create_virtualenv 04:17:26 INFO - Creating virtualenv /builds/slave/test/build/venv 04:17:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:17:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:17:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:17:27 INFO - Using real prefix '/usr' 04:17:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:17:27 INFO - Installing distribute.............................................................................................................................................................................................done. 04:17:29 INFO - Installing pip.................done. 04:17:29 INFO - Return code: 0 04:17:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:29 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 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:17:29 INFO - Using env: {'DISPLAY': ':0', 04:17:29 INFO - 'HOME': '/home/cltbld', 04:17:29 INFO - 'LANG': 'en_US.UTF-8', 04:17:29 INFO - 'LANGUAGE': 'en_US:en', 04:17:29 INFO - 'LOGNAME': 'cltbld', 04:17:29 INFO - 'MAIL': '/var/mail/cltbld', 04:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:29 INFO - 'MOZ_NO_REMOTE': '1', 04:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:29 INFO - 'NO_EM_RESTART': '1', 04:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:29 INFO - 'PWD': '/builds/slave/test', 04:17:29 INFO - 'SHELL': '/bin/bash', 04:17:29 INFO - 'SHLVL': '1', 04:17:29 INFO - 'TERM': 'linux', 04:17:29 INFO - 'TMOUT': '86400', 04:17:29 INFO - 'USER': 'cltbld', 04:17:29 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:29 INFO - '_': '/tools/buildbot/bin/python'} 04:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:17:29 INFO - Cleaning up... 04:17:29 INFO - Return code: 0 04:17:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:29 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 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:17:29 INFO - Using env: {'DISPLAY': ':0', 04:17:29 INFO - 'HOME': '/home/cltbld', 04:17:29 INFO - 'LANG': 'en_US.UTF-8', 04:17:29 INFO - 'LANGUAGE': 'en_US:en', 04:17:29 INFO - 'LOGNAME': 'cltbld', 04:17:29 INFO - 'MAIL': '/var/mail/cltbld', 04:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:29 INFO - 'MOZ_NO_REMOTE': '1', 04:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:29 INFO - 'NO_EM_RESTART': '1', 04:17:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:29 INFO - 'PWD': '/builds/slave/test', 04:17:29 INFO - 'SHELL': '/bin/bash', 04:17:29 INFO - 'SHLVL': '1', 04:17:29 INFO - 'TERM': 'linux', 04:17:29 INFO - 'TMOUT': '86400', 04:17:29 INFO - 'USER': 'cltbld', 04:17:29 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:29 INFO - '_': '/tools/buildbot/bin/python'} 04:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:29 INFO - Downloading/unpacking psutil>=3.1.1 04:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:17:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:31 INFO - Installing collected packages: psutil 04:17:31 INFO - Running setup.py install for psutil 04:17:32 INFO - building 'psutil._psutil_linux' extension 04:17:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:17:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:17:32 INFO - building 'psutil._psutil_posix' extension 04:17:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:17:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:17:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:32 INFO - Successfully installed psutil 04:17:32 INFO - Cleaning up... 04:17:32 INFO - Return code: 0 04:17:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:17:32 INFO - Using env: {'DISPLAY': ':0', 04:17:32 INFO - 'HOME': '/home/cltbld', 04:17:32 INFO - 'LANG': 'en_US.UTF-8', 04:17:32 INFO - 'LANGUAGE': 'en_US:en', 04:17:32 INFO - 'LOGNAME': 'cltbld', 04:17:32 INFO - 'MAIL': '/var/mail/cltbld', 04:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:32 INFO - 'MOZ_NO_REMOTE': '1', 04:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:32 INFO - 'NO_EM_RESTART': '1', 04:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:32 INFO - 'PWD': '/builds/slave/test', 04:17:32 INFO - 'SHELL': '/bin/bash', 04:17:32 INFO - 'SHLVL': '1', 04:17:32 INFO - 'TERM': 'linux', 04:17:32 INFO - 'TMOUT': '86400', 04:17:32 INFO - 'USER': 'cltbld', 04:17:32 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:32 INFO - '_': '/tools/buildbot/bin/python'} 04:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:17:34 INFO - Installing collected packages: mozsystemmonitor 04:17:34 INFO - Running setup.py install for mozsystemmonitor 04:17:34 INFO - Successfully installed mozsystemmonitor 04:17:34 INFO - Cleaning up... 04:17:34 INFO - Return code: 0 04:17:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:34 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 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:17:34 INFO - Using env: {'DISPLAY': ':0', 04:17:34 INFO - 'HOME': '/home/cltbld', 04:17:34 INFO - 'LANG': 'en_US.UTF-8', 04:17:34 INFO - 'LANGUAGE': 'en_US:en', 04:17:34 INFO - 'LOGNAME': 'cltbld', 04:17:34 INFO - 'MAIL': '/var/mail/cltbld', 04:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:34 INFO - 'MOZ_NO_REMOTE': '1', 04:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:34 INFO - 'NO_EM_RESTART': '1', 04:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:34 INFO - 'PWD': '/builds/slave/test', 04:17:34 INFO - 'SHELL': '/bin/bash', 04:17:34 INFO - 'SHLVL': '1', 04:17:34 INFO - 'TERM': 'linux', 04:17:34 INFO - 'TMOUT': '86400', 04:17:34 INFO - 'USER': 'cltbld', 04:17:34 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:34 INFO - '_': '/tools/buildbot/bin/python'} 04:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:34 INFO - Downloading/unpacking jsonschema==2.5.1 04:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:17:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:37 INFO - Downloading functools32-3.2.3-2.tar.gz 04:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:17:37 INFO - warning: no files found matching '*.txt' 04:17:37 INFO - no previously-included directories found matching 'build' 04:17:37 INFO - no previously-included directories found matching 'dist' 04:17:37 INFO - no previously-included directories found matching '.git*' 04:17:37 INFO - Installing collected packages: jsonschema, functools32 04:17:37 INFO - Running setup.py install for functools32 04:17:37 INFO - warning: no files found matching '*.txt' 04:17:37 INFO - no previously-included directories found matching 'build' 04:17:37 INFO - no previously-included directories found matching 'dist' 04:17:37 INFO - no previously-included directories found matching '.git*' 04:17:37 INFO - Successfully installed jsonschema functools32 04:17:37 INFO - Cleaning up... 04:17:37 INFO - Return code: 0 04:17:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:37 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:37 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:17:37 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 functools32==3.2.3-2 04:17:37 INFO - Using env: {'DISPLAY': ':0', 04:17:37 INFO - 'HOME': '/home/cltbld', 04:17:37 INFO - 'LANG': 'en_US.UTF-8', 04:17:37 INFO - 'LANGUAGE': 'en_US:en', 04:17:37 INFO - 'LOGNAME': 'cltbld', 04:17:37 INFO - 'MAIL': '/var/mail/cltbld', 04:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:37 INFO - 'MOZ_NO_REMOTE': '1', 04:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:37 INFO - 'NO_EM_RESTART': '1', 04:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:37 INFO - 'PWD': '/builds/slave/test', 04:17:37 INFO - 'SHELL': '/bin/bash', 04:17:37 INFO - 'SHLVL': '1', 04:17:37 INFO - 'TERM': 'linux', 04:17:37 INFO - 'TMOUT': '86400', 04:17:37 INFO - 'USER': 'cltbld', 04:17:37 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:37 INFO - '_': '/tools/buildbot/bin/python'} 04:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:17:37 INFO - Cleaning up... 04:17:37 INFO - Return code: 0 04:17:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:37 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 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:17:37 INFO - Using env: {'DISPLAY': ':0', 04:17:37 INFO - 'HOME': '/home/cltbld', 04:17:37 INFO - 'LANG': 'en_US.UTF-8', 04:17:37 INFO - 'LANGUAGE': 'en_US:en', 04:17:37 INFO - 'LOGNAME': 'cltbld', 04:17:37 INFO - 'MAIL': '/var/mail/cltbld', 04:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:37 INFO - 'MOZ_NO_REMOTE': '1', 04:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:37 INFO - 'NO_EM_RESTART': '1', 04:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:37 INFO - 'PWD': '/builds/slave/test', 04:17:37 INFO - 'SHELL': '/bin/bash', 04:17:37 INFO - 'SHLVL': '1', 04:17:37 INFO - 'TERM': 'linux', 04:17:37 INFO - 'TMOUT': '86400', 04:17:37 INFO - 'USER': 'cltbld', 04:17:37 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:37 INFO - '_': '/tools/buildbot/bin/python'} 04:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:37 INFO - Downloading/unpacking blobuploader==1.2.4 04:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:39 INFO - Downloading blobuploader-1.2.4.tar.gz 04:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:17:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:17:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:40 INFO - Downloading docopt-0.6.1.tar.gz 04:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:17:40 INFO - Installing collected packages: blobuploader, requests, docopt 04:17:40 INFO - Running setup.py install for blobuploader 04:17:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:17:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:17:40 INFO - Running setup.py install for requests 04:17:40 INFO - Running setup.py install for docopt 04:17:40 INFO - Successfully installed blobuploader requests docopt 04:17:40 INFO - Cleaning up... 04:17:41 INFO - Return code: 0 04:17:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:41 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 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:17:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:17:41 INFO - Using env: {'DISPLAY': ':0', 04:17:41 INFO - 'HOME': '/home/cltbld', 04:17:41 INFO - 'LANG': 'en_US.UTF-8', 04:17:41 INFO - 'LANGUAGE': 'en_US:en', 04:17:41 INFO - 'LOGNAME': 'cltbld', 04:17:41 INFO - 'MAIL': '/var/mail/cltbld', 04:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:41 INFO - 'MOZ_NO_REMOTE': '1', 04:17:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:41 INFO - 'NO_EM_RESTART': '1', 04:17:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:41 INFO - 'PWD': '/builds/slave/test', 04:17:41 INFO - 'SHELL': '/bin/bash', 04:17:41 INFO - 'SHLVL': '1', 04:17:41 INFO - 'TERM': 'linux', 04:17:41 INFO - 'TMOUT': '86400', 04:17:41 INFO - 'USER': 'cltbld', 04:17:41 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:41 INFO - '_': '/tools/buildbot/bin/python'} 04:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:41 INFO - Running setup.py (path:/tmp/pip-1UaZyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:41 INFO - Running setup.py (path:/tmp/pip-ndTeVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:41 INFO - Running setup.py (path:/tmp/pip-HcLbCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:41 INFO - Running setup.py (path:/tmp/pip-XV7Jxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:41 INFO - Running setup.py (path:/tmp/pip-kuuI7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:41 INFO - Running setup.py (path:/tmp/pip-48kIKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:41 INFO - Running setup.py (path:/tmp/pip-I6HPMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:41 INFO - Running setup.py (path:/tmp/pip-aQgstx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:41 INFO - Running setup.py (path:/tmp/pip-e41QQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:41 INFO - Running setup.py (path:/tmp/pip-5LPS8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:41 INFO - Running setup.py (path:/tmp/pip-BkyPfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:42 INFO - Running setup.py (path:/tmp/pip-5tjPzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:42 INFO - Running setup.py (path:/tmp/pip-QzqYxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:42 INFO - Running setup.py (path:/tmp/pip-V3EQe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:42 INFO - Running setup.py (path:/tmp/pip-DnNmM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:42 INFO - Running setup.py (path:/tmp/pip-OAXZKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:42 INFO - Running setup.py (path:/tmp/pip-mJ815X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:17:42 INFO - Running setup.py install for manifestparser 04:17:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:17:42 INFO - Running setup.py install for mozcrash 04:17:42 INFO - Running setup.py install for mozdebug 04:17:42 INFO - Running setup.py install for mozdevice 04:17:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:17:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:17:42 INFO - Running setup.py install for mozfile 04:17:42 INFO - Running setup.py install for mozhttpd 04:17:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Running setup.py install for mozinfo 04:17:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Running setup.py install for mozInstall 04:17:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Running setup.py install for mozleak 04:17:43 INFO - Running setup.py install for mozlog 04:17:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Running setup.py install for moznetwork 04:17:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Running setup.py install for mozprocess 04:17:43 INFO - Running setup.py install for mozprofile 04:17:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Running setup.py install for mozrunner 04:17:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:17:43 INFO - Running setup.py install for mozscreenshot 04:17:44 INFO - Running setup.py install for moztest 04:17:44 INFO - Running setup.py install for mozversion 04:17:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:17:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:17:44 INFO - Cleaning up... 04:17:44 INFO - Return code: 0 04:17:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:44 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 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:17:44 INFO - Using env: {'DISPLAY': ':0', 04:17:44 INFO - 'HOME': '/home/cltbld', 04:17:44 INFO - 'LANG': 'en_US.UTF-8', 04:17:44 INFO - 'LANGUAGE': 'en_US:en', 04:17:44 INFO - 'LOGNAME': 'cltbld', 04:17:44 INFO - 'MAIL': '/var/mail/cltbld', 04:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:44 INFO - 'MOZ_NO_REMOTE': '1', 04:17:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:44 INFO - 'NO_EM_RESTART': '1', 04:17:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:44 INFO - 'PWD': '/builds/slave/test', 04:17:44 INFO - 'SHELL': '/bin/bash', 04:17:44 INFO - 'SHLVL': '1', 04:17:44 INFO - 'TERM': 'linux', 04:17:44 INFO - 'TMOUT': '86400', 04:17:44 INFO - 'USER': 'cltbld', 04:17:44 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:44 INFO - '_': '/tools/buildbot/bin/python'} 04:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:44 INFO - Running setup.py (path:/tmp/pip-tdQ8bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:44 INFO - Running setup.py (path:/tmp/pip-vfc6FA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:44 INFO - Running setup.py (path:/tmp/pip-sb9SRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:44 INFO - Running setup.py (path:/tmp/pip-PoBXKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:44 INFO - Running setup.py (path:/tmp/pip-VuHc7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:44 INFO - Running setup.py (path:/tmp/pip-DrTCbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:44 INFO - Running setup.py (path:/tmp/pip-Bn3dgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:44 INFO - Running setup.py (path:/tmp/pip-NW3HVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:45 INFO - Running setup.py (path:/tmp/pip-zTrRtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:45 INFO - Running setup.py (path:/tmp/pip-VsitTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:45 INFO - Running setup.py (path:/tmp/pip-6glDSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:45 INFO - Running setup.py (path:/tmp/pip-yhAHHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:45 INFO - Running setup.py (path:/tmp/pip-RANZhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:45 INFO - Running setup.py (path:/tmp/pip-DITDgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:45 INFO - Running setup.py (path:/tmp/pip-RhAcmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:45 INFO - Running setup.py (path:/tmp/pip-KRGINg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:45 INFO - Running setup.py (path:/tmp/pip-YftJhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17: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 04:17:47 INFO - Downloading blessings-1.6.tar.gz 04:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:17:47 INFO - Installing collected packages: blessings 04:17:47 INFO - Running setup.py install for blessings 04:17:47 INFO - Successfully installed blessings 04:17:47 INFO - Cleaning up... 04:17:47 INFO - Return code: 0 04:17:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:17:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:48 INFO - Reading from file tmpfile_stdout 04:17:48 INFO - Current package versions: 04:17:48 INFO - argparse == 1.2.1 04:17:48 INFO - blessings == 1.6 04:17:48 INFO - blobuploader == 1.2.4 04:17:48 INFO - docopt == 0.6.1 04:17:48 INFO - functools32 == 3.2.3-2 04:17:48 INFO - jsonschema == 2.5.1 04:17:48 INFO - manifestparser == 1.1 04:17:48 INFO - mozInstall == 1.12 04:17:48 INFO - mozcrash == 0.17 04:17:48 INFO - mozdebug == 0.1 04:17:48 INFO - mozdevice == 0.48 04:17:48 INFO - mozfile == 1.2 04:17:48 INFO - mozhttpd == 0.7 04:17:48 INFO - mozinfo == 0.9 04:17:48 INFO - mozleak == 0.1 04:17:48 INFO - mozlog == 3.3 04:17:48 INFO - moznetwork == 0.27 04:17:48 INFO - mozprocess == 0.23 04:17:48 INFO - mozprofile == 0.28 04:17:48 INFO - mozrunner == 6.12 04:17:48 INFO - mozscreenshot == 0.1 04:17:48 INFO - mozsystemmonitor == 0.3 04:17:48 INFO - moztest == 0.7 04:17:48 INFO - mozversion == 1.4 04:17:48 INFO - psutil == 3.1.1 04:17:48 INFO - requests == 1.2.3 04:17:48 INFO - wsgiref == 0.1.2 04:17:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:48 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 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:17:48 INFO - Using env: {'DISPLAY': ':0', 04:17:48 INFO - 'HOME': '/home/cltbld', 04:17:48 INFO - 'LANG': 'en_US.UTF-8', 04:17:48 INFO - 'LANGUAGE': 'en_US:en', 04:17:48 INFO - 'LOGNAME': 'cltbld', 04:17:48 INFO - 'MAIL': '/var/mail/cltbld', 04:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:48 INFO - 'MOZ_NO_REMOTE': '1', 04:17:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:48 INFO - 'NO_EM_RESTART': '1', 04:17:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:48 INFO - 'PWD': '/builds/slave/test', 04:17:48 INFO - 'SHELL': '/bin/bash', 04:17:48 INFO - 'SHLVL': '1', 04:17:48 INFO - 'TERM': 'linux', 04:17:48 INFO - 'TMOUT': '86400', 04:17:48 INFO - 'USER': 'cltbld', 04:17:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:48 INFO - '_': '/tools/buildbot/bin/python'} 04:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:17:48 INFO - Cleaning up... 04:17:48 INFO - Return code: 0 04:17:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1694180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, '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': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:17:48 INFO - Using env: {'DISPLAY': ':0', 04:17:48 INFO - 'HOME': '/home/cltbld', 04:17:48 INFO - 'LANG': 'en_US.UTF-8', 04:17:48 INFO - 'LANGUAGE': 'en_US:en', 04:17:48 INFO - 'LOGNAME': 'cltbld', 04:17:48 INFO - 'MAIL': '/var/mail/cltbld', 04:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:48 INFO - 'MOZ_NO_REMOTE': '1', 04:17:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:48 INFO - 'NO_EM_RESTART': '1', 04:17:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:48 INFO - 'PWD': '/builds/slave/test', 04:17:48 INFO - 'SHELL': '/bin/bash', 04:17:48 INFO - 'SHLVL': '1', 04:17:48 INFO - 'TERM': 'linux', 04:17:48 INFO - 'TMOUT': '86400', 04:17:48 INFO - 'USER': 'cltbld', 04:17:48 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:48 INFO - '_': '/tools/buildbot/bin/python'} 04:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:17:48 INFO - Cleaning up... 04:17:48 INFO - Return code: 0 04:17:48 INFO - Running post-action listener: _resource_record_post_action 04:17:48 INFO - Running post-action listener: _start_resource_monitoring 04:17:48 INFO - Starting resource monitoring. 04:17:48 INFO - [mozharness: 2016-07-30 11:17:48.555003Z] Finished create-virtualenv step (success) 04:17:48 INFO - [mozharness: 2016-07-30 11:17:48.555489Z] Running install step. 04:17:48 INFO - Running pre-action listener: _resource_record_pre_action 04:17:48 INFO - Running main action method: install 04:17:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:48 INFO - Reading from file tmpfile_stdout 04:17:48 INFO - Detecting whether we're running mozinstall >=1.0... 04:17:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:17:48 INFO - Reading from file tmpfile_stdout 04:17:48 INFO - Output received: 04:17:48 INFO - Usage: mozinstall [options] installer 04:17:48 INFO - Options: 04:17:48 INFO - -h, --help show this help message and exit 04:17:48 INFO - -d DEST, --destination=DEST 04:17:48 INFO - Directory to install application into. [default: 04:17:48 INFO - "/builds/slave/test"] 04:17:48 INFO - --app=APP Application being installed. [default: firefox] 04:17:48 INFO - mkdir: /builds/slave/test/build/application 04:17:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:18:05 INFO - Reading from file tmpfile_stdout 04:18:05 INFO - Output received: 04:18:05 INFO - /builds/slave/test/build/application/firefox/firefox 04:18:05 INFO - Running post-action listener: _resource_record_post_action 04:18:05 INFO - [mozharness: 2016-07-30 11:18:05.327287Z] Finished install step (success) 04:18:05 INFO - [mozharness: 2016-07-30 11:18:05.327567Z] Running run-tests step. 04:18:05 INFO - Running pre-action listener: _resource_record_pre_action 04:18:05 INFO - Running main action method: run_tests 04:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:18:05 INFO - Python 2.7.3 04:18:05 INFO - Return code: 0 04:18:05 INFO - grabbing minidump binary from tooltool 04:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:18:05 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 0x16923b0>, '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 0x16932b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1693740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:18:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:18:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:18:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:18:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:18:05 INFO - Return code: 0 04:18:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:18:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:18:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:18:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:18:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 04:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 04:18:05 INFO - Using env: {'DISPLAY': ':0', 04:18:05 INFO - 'HOME': '/home/cltbld', 04:18:05 INFO - 'LANG': 'en_US.UTF-8', 04:18:05 INFO - 'LANGUAGE': 'en_US:en', 04:18:05 INFO - 'LOGNAME': 'cltbld', 04:18:05 INFO - 'MAIL': '/var/mail/cltbld', 04:18:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:18:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:05 INFO - 'MOZ_NO_REMOTE': '1', 04:18:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:05 INFO - 'NO_EM_RESTART': '1', 04:18:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:05 INFO - 'PWD': '/builds/slave/test', 04:18:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:18:05 INFO - 'SHELL': '/bin/bash', 04:18:05 INFO - 'SHLVL': '1', 04:18:05 INFO - 'TERM': 'linux', 04:18:05 INFO - 'TMOUT': '86400', 04:18:05 INFO - 'USER': 'cltbld', 04:18:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1469877434.507722-2103265578', 04:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:05 INFO - '_': '/tools/buildbot/bin/python'} 04:18:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 04:18:05 INFO - mozversion application_buildid: 20160729220924 04:18:05 INFO - mozversion application_changeset: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 04:18:05 INFO - mozversion application_display_name: Nightly 04:18:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:18:05 INFO - mozversion application_name: Firefox 04:18:05 INFO - mozversion application_remotingname: firefox 04:18:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:18:05 INFO - mozversion application_vendor: Mozilla 04:18:05 INFO - mozversion application_version: 50.0a1 04:18:05 INFO - mozversion platform_buildid: 20160729220924 04:18:05 INFO - mozversion platform_changeset: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 04:18:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:18:05 INFO - mozversion platform_version: 50.0a1 04:18:05 INFO - using testdate: 1469877485 04:18:05 INFO - actual date: 1469877485 04:18:05 INFO - starting webserver on 'localhost:53746' 04:18:05 INFO - SUITE-START | Running 2 tests 04:18:05 INFO - TEST-START | dromaeo_css 04:18:05 INFO - operating with platform_type : linux_ 04:18:05 INFO - Initialising browser for dromaeo_css test... 04:18:05 INFO - TEST-INFO | started process 22946 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDxMCk8/profile http://localhost:53746/getInfo.html) 04:18:09 INFO - PROCESS | 22946 | JavaScript error: , line 0: uncaught exception: undefined 04:18:09 INFO - PROCESS | 22946 | __metrics Screen width/height:1600/1200 04:18:09 INFO - PROCESS | 22946 | colorDepth:24 04:18:09 INFO - PROCESS | 22946 | Browser inner width/height: 1024/697 04:18:09 INFO - PROCESS | 22946 | __metrics 04:18:10 INFO - PROCESS | 22946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:18:10 INFO - PROCESS | 22946 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:18:11 INFO - TEST-INFO | 22946: exit 0 04:18:11 INFO - Browser initialized. 04:18:11 INFO - Running cycle 1/1 for dromaeo_css test... 04:18:11 INFO - TEST-INFO | started process 23023 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDxMCk8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:23 INFO - PROCESS | 23023 | RSS: Main: 159543296 04:18:23 INFO - PROCESS | 23023 | 04:19:15 INFO - PROCESS | 23023 | 1469877555992 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:21:27 INFO - PROCESS | 23023 | Cycle 1(1): loaded http://localhost:53746/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53746/tests/dromaeo/cssquery-ext.html) 04:21:28 INFO - PROCESS | 23023 | RSS: Main: 247255040 04:21:28 INFO - PROCESS | 23023 | 04:24:31 INFO - PROCESS | 23023 | Cycle 1(1): loaded http://localhost:53746/tests/dromaeo/cssquery-ext.html (next: http://localhost:53746/tests/dromaeo/cssquery-jquery.html) 04:24:32 INFO - PROCESS | 23023 | RSS: Main: 242950144 04:24:32 INFO - PROCESS | 23023 | 04:27:34 INFO - PROCESS | 23023 | Cycle 1(1): loaded http://localhost:53746/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53746/tests/dromaeo/cssquery-mootools.html) 04:27:34 INFO - PROCESS | 23023 | RSS: Main: 186982400 04:27:34 INFO - PROCESS | 23023 | 04:30:37 INFO - PROCESS | 23023 | Cycle 1(1): loaded http://localhost:53746/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53746/tests/dromaeo/cssquery-prototype.html) 04:30:37 INFO - PROCESS | 23023 | RSS: Main: 187809792 04:30:37 INFO - PROCESS | 23023 | 04:33:40 INFO - PROCESS | 23023 | Cycle 1(1): loaded http://localhost:53746/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53746/tests/dromaeo/cssquery-yui.html) 04:33:41 INFO - PROCESS | 23023 | RSS: Main: 187621376 04:33:41 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | Cycle 1(1): loaded http://localhost:53746/tests/dromaeo/cssquery-yui.html (next: http://localhost:53746/tests/dromaeo/cssquery-dojo.html) 04:36:44 INFO - PROCESS | 23023 | RSS: Main: 280698880 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | __start_tp_report 04:36:44 INFO - PROCESS | 23023 | _x_x_mozilla_page_load 04:36:44 INFO - PROCESS | 23023 | _x_x_mozilla_page_load_details 04:36:44 INFO - PROCESS | 23023 | |i|pagename|runs| 04:36:44 INFO - PROCESS | 23023 | |0;dojo.html;2653.4958357911996;2658.9069382571615;2658.9762941267613;2694.5419278722616;2700.9459810803783;4691.812327506896;4757.405324334458;4809.086273608012;4851.660383773137;4874.317595536623;4789.090072886149;4794.976025119879;4805.663603547744;4819.903601927959;4856.684315519488;4337.219300525904;4366.759828209447;4422.447194100738;4486.147631949935;4512.503624601308;4448.777561121947;4480.260756975095;4486.057927835159;4497.257952437843;4516.458025036984;4326.6754993375625;4368.191884501346;4379.0585024219945;4379.890502737425;4383.3863259143745;4345.152695224432;4349.739015659071;4362.105768317488;4420.734755914656;4427.40230068943;4369.6722745794195;4369.868903932887;4394.4946331172005;4406.471223453174;4420.602145806893;4666.883327916796;4774.856754297377;4838.2016967200725;4839.249916262985;4853.538913803183;5061.772220250097;5100.923486147711;5146.9485305146845;5150.381954165487;5204.505571970694;5176.352955880515;5178.97410512945;5199.610029247821;5238.92141617876;5250.816221432232;4680.251159814414;4745.217039188564;4847.95152048482;4886.413630364379;4892.241702536113;5223.242629818655;5224.268602395668;5273.815416460406;5293.947060529423;5302.284191634157;5001.64988450805;5010.574101201366;5024.598032157419;5063.974680126576;5065.6200784941275;5027.120253955543;5071.41678706946;5121.411037730634;5170.276161337416;5189.143791274473;5092.694438333712;5114.43741188469;5118.564921981673;5159.509846564571;5163.586913046947;5082.085224659523;5096.992417901847;5098.694078355311;5099.617528685363;5152.536271735562;4165.250254954138;4199.664026877904;4328.978355108205;4329.047609525899;4373.300271956536;3740.9625903741157;3772.641599048086;3792.222594368203;3800.087978885103;3840.577536470986;4393.20922234001;4399.64802815774;4405.07493426384;4437.977810110993;4440.111977604492;5097.133487307166;5103.795848166032;5154.871128221749;5157.097507936096;5157.793688252428;4227.788610569459;4343.826246950087;4367.192069467095;4370.890727731832;4403.097365040159;4645.303204519281;4647.674662773641;4657.86026419208;4674.322223277673;4694.624430045588;4791.065742180242;4791.880202994953;4808.687435316763;4814.253790662453;4850.4179498459;4995.750212489288;5118.3857937046305;5154.7938082476285;5164.32863727728;5169.53474187325;4807.519248075165;4832.130216560912;4834.468208497133;4836.322914791862;4856.878578035577;5105.132127538253;5108.438071812172;5110.1568241239775;5114.488551145005;5119.897602047864;5003.299538064601;5134.922976155286;5142.64001519905;5175.689458632494;5213.817516386908;5066.746662666926;5066.923996139987;5071;5072.54347108762;5090.847274581768;5054.974725126351;5056.873578160576;5059.215821547666;5066;5096.05722941257;4818.180909245507;4922.187839006667;4964.950350496593;4980.526849949249;4989.55094041538;5078.695278283315;5082.237664350377;5086.135356989246;5092.7199004053655;5122.1548444507725;4952.479989601185;4952.77712502931;4953.950460495349;4966.106100901872;4987.376577927759 04:36:44 INFO - PROCESS | 23023 | |1;ext.html;558.9661514941596;577.4485366475014;579.3829571506343;584.3922320786382;597.6563476001301;39655.40516892245;41054.178916421726;41164.58835411645;41275.58724412755;44493;41136;41533.79233103838;41608.58391416085;41615.37576936341;41720.58279417205;7429.554226746385;7733.651985660631;7880.369570434352;7883.2510911463605;9606.903930960705;9703.61185552577;10052.69841904744;10084.243681723863;10085.041921017528;10095.646652367168;9668.419894806335;9685.854712179324;9689.51552422376;9716.368436051669;9724.5899344595;10071.69784906454;10103.191744660446;10103.292769506137;10131.594736210616;10134.138598219124;9946;10011.59953601855;10011.949940250252;10019.699409017741;10023.498825058718;39747.80126099351;39840.40239396382;39920.60079399198;40109.19781604381;40125;3049.1005153479555;3146.638136614296;3326.334733053399;3670.9632903671154;3869.032741814546;29657.40685186284;29828.701712983027;30439.391212175855;31754;32494.18764530882;3498.8892415106325;3512.8067956262394;3552.7335449841107;3555.591107022674;3609.2781443711365;272319;274460;274810;274904;275283.6235818828;56378.43621563814;56813;56936.71531642315;57061.71469142711;57089.71455142698;33650.49524257138;34388;34709.65290347115;36038.8198059008;36307.818460908056;14466;14486.927565362317;14497.492587759378;14502.202378869053;14508.202048887413;10740.94629526863;11002.669919902415;11005.119590432747;11258.493367798468;12279.263244205376;17768.91115544414;18312.542186445444;18322.725159122623;18711.941686414484;19075.236990520225;6641;6761.8647627047185;6785.219699734594;6864.5881247124335;6986.9650651746415;689.5138927056413;740.8851627997561;808.2887059387701;864.5979619476848;890.3722875372847;28537.354543821366;29005.419891602472;29072.854635726686;29565.704342956724;29638.55542166869;886.6143227695973;950.7147855643279;961.7932144588947;968.1577027985697;997.6358629100326;14747.262636868114;15386.538403847902;15474.84525154734;15715.449959251371;15734.213289335488;402806;405565;406768;407153;409676;48955.755221224375;49434.752826235635;49453.25820112701;49498;49560;1021.1830535571513;1074.6883403812963;1096.0245381610373;1123.0678536814423;1124.2411372323813;28274.71725282803;29398.706012939598;29567.26081847971;30200;30247.848760756937;35842.64157358393;36246.63753362433;36249.27501450009;36262.27475450529;36282.8185859069;313640;313806;314143;314376;314656;23474.413139671644;24422.02311907504;24483.265502034967;25372.746272537777;25718.614220787076;39467.80266098651;40090.599094008685;40231.19537609173;40578.594214057484;42317.67252801125;33696.83151584226;36459.81770091133;36606.816965915;37288.6271137296;37542.812285938395;36528.26943461063;37266;37453.81273093617;38398.616013839506;38672;36304.09239769027;37031.8148409267;37112.07219819526;37388;37563.81218093892;32188;34348.65651343555;34665.306693866485;34780.304393912484;34783.82608086943;25569.48861022806;25648.48703025892;25651.743482565682;25732.742672573033;25899.741002589733 04:36:44 INFO - PROCESS | 23023 | |2;jquery.html;354.0811593913793;364.0643546086557;375.7332294071209;383.4306055507571;393.75783892905855;12890.935545322272;15971.201439928016;16708.498745037654;18213.18040688169;18330.633387332244;17608.735868961976;17839.375621853236;17956.730649040273;18030.5492362691;18144.09279536021;4699.248120300753;5072.898542029157;5116.539511443969;5121.052605268037;5122.206058061007;3735.3276410246144;5029.547340739333;5111.13110771168;5545.722713864311;5850.912236316459;5840.591158618899;5848.67832269225;5854.853628659275;5858.7949421770245;5863.618864773776;5653.406392328788;5790.7973220937065;5834.737436815353;5835.766569337221;5836.445537673914;5186;5187.247849061902;5189.42916279209;5191;5196.29849970253;21065;21331.146754129815;21938.232161874257;21951.67072493898;22088.668669969964;1861.255497800877;1868.1966754295647;1885.5286178455385;1897.5066482714594;2004.0480771633358;12941.935290323583;13659.590212293648;13937.233452160128;14278.143311401349;14457.204853733041;1758.1209395302349;1827.2782251010776;1835.7613510243716;1841.8802777819399;1846.796852346241;70671.64664176697;72434.91347629842;72605;74156.62921685411;75338.62330688312;9640.469774162488;9869.753256168653;9959.203263738884;10298.536565854554;10349.844752328721;13914.165150091027;13918.373673184731;13928.651783705489;13932.791008134885;13943.860561394256;7438.962805185939;7439.218882017419;7798.727044553413;7882.093559240646;7993.840123197503;7197.725163405096;7321.341079302888;7549.471536992468;7713.65288562016;7948.920510794934;6255.906161407492;6333.904991425133;6350.968245158745;6356;6508.967455162789;1767.7878654561534;1804.4767017565023;1831.4047934421367;1837.4946889605465;1864.4499872537633;764.1556080530934;768.6080099149362;783.4437549340032;801.9278264956182;860.6514361683528;17068.146592670564;17612.647747045245;17795.911020445074;17973.460796176136;18307;823.197382552007;824.6371596497522;830.2154464031553;836.2766207230824;862.1550880137376;9682.56428460721;10418.895811041944;10504.593329578336;10568.841467377995;10591.205659575562;388160;390006;397377;409146;410257;43279.78360108179;73671.26328736752;74354.62822685852;75804;76964.61517692376;886.2334081019872;889.7375274294099;905.4657751926395;938.1275413865171;942.8403064231035;15811.13038782878;15994.920025400264;16322.918385407997;16446.177691115634;16724.749128762836;38905.6109438902;39903.40144897771;40356.59643403528;41129.97175070647;41363.7931810339;57654.4234557649;57805;58433;58825.411745882;59401.702991484766;6745.426638735762;6885.036094946811;6957.095577574883;7190.7483238086415;7331.41348692114;8965;11832.467538960595;12019.75960480768;12209.267443953391;12575;10055.69832905014;10176;10219.182465402899;10717;10736.194785391282;5725.34158571753;10024.498775061364;10629.25595208328;10752.08607268391;10789.190810689073;9867.40795552269;10007.749806254902;10343.586256549654;10561.683149505527;10761.784764304513;7028.543144695578;7197.496175267681;7301.780946571611;7573.8106547334555;7648.579328137006;9372.531373431164;10483.895161048293;10570.2600817942;10799.838002430128;10810.945945270538 04:36:44 INFO - PROCESS | 23023 | |3;mootools.html;1275.0246061762748;1391.9095258808177;1430.6924011337562;1508.253414559793;1710.2816816936893;6949.409300209482;7601.2018738032475;7919.7624071277805;7920.1683823198455;7935.2461516155945;7555.811104722385;7684.423668224891;7721.961390193055;7900.367970562355;7911.36709063275;2169.0456199272307;2214.0258286354033;2215.634420320645;2216.92240771573;2217.6562632791943;2161.6001039807607;2191.9890400548056;2208.182972300251;2209.0611490116717;2220.578090162864;2180.7056047433553;2184.170015394148;2193.035064571591;2195.2316689158824;2245.2478419729327;2184.497565559914;2186.8906554672203;2191.649336106231;2193.8354623403307;2194.703714998471;2067.8345732341377;2089.122568521225;2095.413284280404;2102.2431924507164;2109.683547467877;7789.182135875708;7992.160823113548;8005.359571234288;8057.073436554812;8059.314958228558;4685.367475390813;4900.509949005107;4909.975450122763;4916.950830491685;4920;2941.617589713345;3043.6195475565555;3081.229692576856;3107.192130046182;3112.2375018120706;935.6257497001186;941.204682043672;946.9526523673788;970.9805803883883;985.5515740338129;10997;11223.943880280627;11227.270227435272;11228.270162439416;11248.268862523992;4389.714668546534;4425.778711064434;4509.88725281864;4538.092381523708;4566.246569316026;9603.135717785433;9617.903820961841;9624.855627165598;9625.51872406377;9634.084761947744;4939.728314942713;4945.134601444733;4947.381577302837;4948.282499037691;4952.108620448354;3714.3685573452576;3764.0966168119453;4036.455078564415;4485;4487.775611219426;3751.8874433767032;3804.4293355996824;3854.190619969838;3874.845006199777;3913.0413048802657;1186.2408058842348;1222.3093951917317;1227.9017678585692;1236.1470585296117;1238.5417395563518;737.6238118559573;743.4535616321999;746.2649290448903;778.1906816910463;802.4423025996877;5366.597505187126;5453.209284653703;5475.178723191553;5519.448055194529;5631.042722737145;412.53177643374386;414.85480081971673;419.67684882640197;422.3095239283793;432.3061486314483;3592.3533763922746;3670.504481894963;3687.262547490459;3703.9629603704157;3767.2653832502397;9452.763680908032;9829.115379615869;9928.751781205528;9978.900210997943;9994.700158995242;9902;10006.09945104944;10018;10023.599056037678;10058.446785426871;7624.58064806441;7638.312551870358;7682.0397450318715;7713.305802477804;7714.4599878008;5444.782208711607;5457.481539253765;5491.588130890119;5529.751161197675;5613.326400831927;9282.164605185295;9430.528473576156;9436.669716559887;9443.527823608929;9530.714078577654;8425.83148337042;9094.27245820346;9198.482250428604;9636.807263854544;9661.661841835783;6918.654067296716;7181.676824542804;7286.16209135935;7287.344139027513;7287.8178045547165;7669.233076692286;7717.073951125901;7772.7668169954995;7776.183500732568;7796.84406311882;6882.552634078769;6888.035675005403;6988.930110698828;7072.646367681493;7244.384227340735;6805.591664500146;6837;6925.134358205224;6983.75556855528;7095.254998225319;6525.771597994048;6629.138212032598;6632.568883022588;6632.801015969529;6633.535652504472;4377.606015458624;4381.518033016302;4438.134563760036;4507.256302710016;4520.480144783261;4527.38880251175;4542.613877820422;4612.354270402214;4685.437747470189;4769.4753577107185 04:36:44 INFO - PROCESS | 23023 | |4;prototype.html;307.96612372639015;313.62835040477034;315.12395540397694;319.6483867745479;320.4600248581141;4879.951200487999;4910.508949105097;4911.459739428665;4918.458969513351;4922.729249891255;4790.9041819163685;4827.758612069391;4860.8541743747655;4895.265710143481;4895.5838753705975;3674.419999400257;3739.289534988357;3790.54513458384;3846.76903390915;3857.6305411578905;3839.5584507781546;3847.8460861565504;3850.4801851750076;3857.4213867919757;3873.341531939563;3807.257584770971;3826.6747326477284;3839.2705385976574;3841.2125514269646;3857.6661120022263;3651.908702282438;3741.38267185914;3849.441830934513;3856.8842934712;3865.1689886674217;3396.490526421032;3423.3910062270693;3433.4892647235138;3439.5356626855305;3468;4717.693349932243;4746.908211111429;4749.335093086971;4764.690295130806;4764.761761911891;4160.855764664711;4420.46954365475;4421.585092770315;4429;4440.534623574245;5051.242313652945;5052.9242061369105;5068.493150684939;5082.339295891547;5092.261622064817;3783.4135708964877;3785.772853628791;3787.8295476703615;3791;3814;5216.426193118792;5267.446918073624;5336.93261347732;5356.785728570852;5367.194920761878;3807.828647710804;3821.808909554567;3831.099691572478;3832.291026160169;3834.827432765532;3879.8836034919;3895.35726605107;3896.493455850721;3911.3546264865977;3916.3538016227003;4358.934615980763;4383.671224658164;4388.363687265392;4409.1181763646755;4411.602955733999;4901.534354236342;5011.924821127686;5024.145895197825;5067.543921047123;5072.923906141411;1333.986170510419;1346.7171893902193;1352.932353382327;1354.6477915741978;1358.3479929633634;911.878389580806;938.784079661668;948.5257371314343;960.3949511807516;964.0889359555154;4250.702450828412;4325.243082460557;4460.330950357472;4472.821087156477;4493.326001099861;5154.974225128851;5191.740412979336;5207.505286997729;5213.426523082458;5214.687118772886;4460.77696115193;4466.821327146943;4467.6597020893605;4482.731036137842;4488.461384633865;2637.31429828246;2703.5539136042335;2715.8506282154276;2717.8641067946523;2744.4493860968887;4823.397075365579;4842.15262329091;4846.248831431134;4874.341963834908;4888.853334399973;5049.368828896388;5272.815451459181;5277.393099793496;5300.257963885059;5305.628605997543;4848.80604775805;4852.611791056707;4869.269609558523;4881.609471242363;4896.510348965075;5215.191645294985;5221.242919776535;5225.895482090261;5242.18746094356;5255.553277971415;4947.233178857283;5038.949610503848;5098.694078355311;5098.97450512745;5102.729285467931;4881.536254056001;4890.462049174659;4891.486393928586;4905.926411103907;4916.06594746997;3814.5994670560303;3830.616938306147;3856.421536769507;3879.282332768447;3892.8832135035996;5006.774695138654;5243.973780131075;5265.183896496049;5266.631335806457;5280.128778751463;4961.727105009115;4998.525140111684;5004.274380214922;5009.918899876622;5032.572231360376;4992.251162325535;4999.400071991385;5000.749962501787;5000.799968001239;5044.672096313728;4734.124187025273;4929.433115191799;4939.48135445773;4940.333055037668;4961.776720047678;4888.362398596368;4977.726225057657;5072.137736584716;5087.364079490064;5105.2086926526445;4991.179983309418;4994.200927851526;5009.998000400007;5052.671576347526;5054 04:36:44 INFO - PROCESS | 23023 | |5;yui.html;636.866258085802;642.3672682407828;670.8054664147397;725.4414101142121;750.8460765543065;12130.636080917575;12951.74096518069;12966.222026678393;12988.74022519549;12999.090063695558;12993.675158121028;13097.279649619268;13137.67155821105;13156.14485058473;13166.144200626952;620.7982405718137;635.4027214418455;759.654357267442;760.9771706848803;783.4280974888335;775.5153029356652;786.6066966516742;786.7954331873725;788.113372455987;788.2275370137269;845.3364109174299;846.2257034813139;846.2764336492303;846.6571038729325;847.478800537668;660.9140811694449;803.1245941923273;830.9252167304911;837.351052933975;838.4969018588829;608.9695515224221;609.4180058044557;610.9572329936906;612.8406614280275;613.4938675592654;13113.147645403018;13144.6713832154;13146.934265328708;13156;13173.934130329382;12252.326122063283;12638.368081595976;12781.552645657357;13032;13534.25561594112;11512;11994.760104797855;12003.519859205622;12137.514499420013;12850.293233872133;4134.090500089976;4148.585141485857;4190.685698572589;4236.533981262059;4298.914021719579;64180.67909660468;64212.35787642157;64892.35107648958;65644.67177664129;66808.66595667039;10142.746431339128;10203.642872499575;10327.380357178594;10390.116840068678;10454.843177352346;17955.820441795677;18188;18191.727124093148;18195.81804181968;18215.907045577307;6587.769428069994;6639.701213445406;6673.1992160941;6864.313568643096;6977.755778547727;3654.6710796028483;3700.518932538753;3708.3881159608363;3737.8131093445763;3764.0778009387877;2772.8336299822076;2832.3910359272445;2855.914322570326;2872.3250036241466;2873.956890646642;500.1622282676502;504.78798904460217;510.2168171856183;510.8007876928001;513.4608660906034;466.1516040805701;492.48781267481417;492.94824043475637;495.65799598277073;498.461661405681;8234.341252699769;8433.367497437717;8847.336449766293;8922.821543569093;9033.683821066232;493.90121975605024;514.531776083762;530.3105962249138;580.0921557762089;607.9513638908877;3978.7214894957074;4048.4939382577177;4113.650339721097;4217.135487225111;4330.631896288788;100156.49921750344;108931;111445.09942151986;112085;113249;91443;91859;92110;92470.53764731268;100435.99564004413;50321.24518132231;83000.58499707463;86757;87296;87313;8139.75580732579;8186;8331.541765202972;8374.958125209336;8549.487030778173;33864.83067584646;39895.80052099721;46190.538094619966;46353.304700428846;48231.75884120557;64846.675766620865;65641;70685;71142.64428678031;71847;10122.746931326776;10588.364698118137;10718.73203169927;10753.301035432673;10836.078933290759;11280.32318060919;11706.70733231676;11784.41077946082;11876.346800926032;12260.509579616715;10706.571737130738;10944.398058106563;11309.604163854554;11347.205695601559;11443;10528.42093684824;11033.944830275797;11247.381394023407;11682.182247242612;11889;10021.899781002097;10183.7454063649;10245.59017639316;10516.631917882838;10603.41681207541;9156.175944165057;9496.952515237379;9518.476483793458;9650.903490965282;9774.75563110928;8674.571120086386;8731.607077681392;8794.29645628361;8798.604062817061;9053.230475409664 04:36:44 INFO - PROCESS | 23023 | __end_tp_report 04:36:44 INFO - PROCESS | 23023 | __start_cc_report 04:36:44 INFO - PROCESS | 23023 | _x_x_mozilla_cycle_collect,1026 04:36:44 INFO - PROCESS | 23023 | __end_cc_report 04:36:44 INFO - PROCESS | 23023 | __startTimestamp1469878604648__endTimestamp 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | ------- Summary: start ------- 04:36:44 INFO - PROCESS | 23023 | Number of tests: 6 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | [#0] dojo.html Cycles:165 Average:4748.36 Median:4856.88 stddev:508.57 (10.5%) stddev-sans-first:482.85 04:36:44 INFO - PROCESS | 23023 | Values: 2653.5 2658.9 2659.0 2694.5 2700.9 4691.8 4757.4 4809.1 4851.7 4874.3 4789.1 4795.0 4805.7 4819.9 4856.7 4337.2 4366.8 4422.4 4486.1 4512.5 4448.8 4480.3 4486.1 4497.3 4516.5 4326.7 4368.2 4379.1 4379.9 4383.4 4345.2 4349.7 4362.1 4420.7 4427.4 4369.7 4369.9 4394.5 4406.5 4420.6 4666.9 4774.9 4838.2 4839.2 4853.5 5061.8 5100.9 5146.9 5150.4 5204.5 5176.4 5179.0 5199.6 5238.9 5250.8 4680.3 4745.2 4848.0 4886.4 4892.2 5223.2 5224.3 5273.8 5293.9 5302.3 5001.6 5010.6 5024.6 5064.0 5065.6 5027.1 5071.4 5121.4 5170.3 5189.1 5092.7 5114.4 5118.6 5159.5 5163.6 5082.1 5097.0 5098.7 5099.6 5152.5 4165.3 4199.7 4329.0 4329.0 4373.3 3741.0 3772.6 3792.2 3800.1 3840.6 4393.2 4399.6 4405.1 4438.0 4440.1 5097.1 5103.8 5154.9 5157.1 5157.8 4227.8 4343.8 4367.2 4370.9 4403.1 4645.3 4647.7 4657.9 4674.3 4694.6 4791.1 4791.9 4808.7 4814.3 4850.4 4995.8 5118.4 5154.8 5164.3 5169.5 4807.5 4832.1 4834.5 4836.3 4856.9 5105.1 5108.4 5110.2 5114.5 5119.9 5003.3 5134.9 5142.6 5175.7 5213.8 5066.7 5066.9 5071.0 5072.5 5090.8 5055.0 5056.9 5059.2 5066.0 5096.1 4818.2 4922.2 4965.0 4980.5 4989.6 5078.7 5082.2 5086.1 5092.7 5122.2 4952.5 4952.8 4954.0 4966.1 4987.4 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | [#1] ext.html Cycles:180 Average:48491.48 Median:19824.05 stddev:88443.23 (446.1%) stddev-sans-first:88618.11 04:36:44 INFO - PROCESS | 23023 | Values: 559.0 577.4 579.4 584.4 597.7 39655.4 41054.2 41164.6 41275.6 44493.0 41136.0 41533.8 41608.6 41615.4 41720.6 7429.6 7733.7 7880.4 7883.3 9606.9 9703.6 10052.7 10084.2 10085.0 10095.6 9668.4 9685.9 9689.5 9716.4 9724.6 10071.7 10103.2 10103.3 10131.6 10134.1 9946.0 10011.6 10011.9 10019.7 10023.5 39747.8 39840.4 39920.6 40109.2 40125.0 3049.1 3146.6 3326.3 3671.0 3869.0 29657.4 29828.7 30439.4 31754.0 32494.2 3498.9 3512.8 3552.7 3555.6 3609.3 272319.0 274460.0 274810.0 274904.0 275283.6 56378.4 56813.0 56936.7 57061.7 57089.7 33650.5 34388.0 34709.7 36038.8 36307.8 14466.0 14486.9 14497.5 14502.2 14508.2 10740.9 11002.7 11005.1 11258.5 12279.3 17768.9 18312.5 18322.7 18711.9 19075.2 6641.0 6761.9 6785.2 6864.6 6987.0 689.5 740.9 808.3 864.6 890.4 28537.4 29005.4 29072.9 29565.7 29638.6 886.6 950.7 961.8 968.2 997.6 14747.3 15386.5 15474.8 15715.4 15734.2 402806.0 405565.0 406768.0 407153.0 409676.0 48955.8 49434.8 49453.3 49498.0 49560.0 1021.2 1074.7 1096.0 1123.1 1124.2 28274.7 29398.7 29567.3 30200.0 30247.8 35842.6 36246.6 36249.3 36262.3 36282.8 313640.0 313806.0 314143.0 314376.0 314656.0 23474.4 24422.0 24483.3 25372.7 25718.6 39467.8 40090.6 40231.2 40578.6 42317.7 33696.8 36459.8 36606.8 37288.6 37542.8 36528.3 37266.0 37453.8 38398.6 38672.0 36304.1 37031.8 37112.1 37388.0 37563.8 32188.0 34348.7 34665.3 34780.3 34783.8 25569.5 25648.5 25651.7 25732.7 25899.7 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | [#2] jquery.html Cycles:180 Average:25037.64 Median:225693.49 stddev:65842.14 (29.2%) stddev-sans-first:66000.76 04:36:44 INFO - PROCESS | 23023 | Values: 354.1 364.1 375.7 383.4 393.8 12890.9 15971.2 16708.5 18213.2 18330.6 17608.7 17839.4 17956.7 18030.5 18144.1 4699.2 5072.9 5116.5 5121.1 5122.2 3735.3 5029.5 5111.1 5545.7 5850.9 5840.6 5848.7 5854.9 5858.8 5863.6 5653.4 5790.8 5834.7 5835.8 5836.4 5186.0 5187.2 5189.4 5191.0 5196.3 21065.0 21331.1 21938.2 21951.7 22088.7 1861.3 1868.2 1885.5 1897.5 2004.0 12941.9 13659.6 13937.2 14278.1 14457.2 1758.1 1827.3 1835.8 1841.9 1846.8 70671.6 72434.9 72605.0 74156.6 75338.6 9640.5 9869.8 9959.2 10298.5 10349.8 13914.2 13918.4 13928.7 13932.8 13943.9 7439.0 7439.2 7798.7 7882.1 7993.8 7197.7 7321.3 7549.5 7713.7 7948.9 6255.9 6333.9 6351.0 6356.0 6509.0 1767.8 1804.5 1831.4 1837.5 1864.4 764.2 768.6 783.4 801.9 860.7 17068.1 17612.6 17795.9 17973.5 18307.0 823.2 824.6 830.2 836.3 862.2 9682.6 10418.9 10504.6 10568.8 10591.2 388160.0 390006.0 397377.0 409146.0 410257.0 43279.8 73671.3 74354.6 75804.0 76964.6 886.2 889.7 905.5 938.1 942.8 15811.1 15994.9 16322.9 16446.2 16724.7 38905.6 39903.4 40356.6 41130.0 41363.8 57654.4 57805.0 58433.0 58825.4 59401.7 6745.4 6885.0 6957.1 7190.7 7331.4 8965.0 11832.5 12019.8 12209.3 12575.0 10055.7 10176.0 10219.2 10717.0 10736.2 5725.3 10024.5 10629.3 10752.1 10789.2 9867.4 10007.7 10343.6 10561.7 10761.8 7028.5 7197.5 7301.8 7573.8 7648.6 9372.5 10483.9 10570.3 10799.8 10810.9 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | [#3] mootools.html Cycles:180 Average:5294.44 Median:4946.26 stddev:3022.63 (61.1%) stddev-sans-first:3016.01 04:36:44 INFO - PROCESS | 23023 | Values: 1275.0 1391.9 1430.7 1508.3 1710.3 6949.4 7601.2 7919.8 7920.2 7935.2 7555.8 7684.4 7722.0 7900.4 7911.4 2169.0 2214.0 2215.6 2216.9 2217.7 2161.6 2192.0 2208.2 2209.1 2220.6 2180.7 2184.2 2193.0 2195.2 2245.2 2184.5 2186.9 2191.6 2193.8 2194.7 2067.8 2089.1 2095.4 2102.2 2109.7 7789.2 7992.2 8005.4 8057.1 8059.3 4685.4 4900.5 4910.0 4917.0 4920.0 2941.6 3043.6 3081.2 3107.2 3112.2 935.6 941.2 947.0 971.0 985.6 10997.0 11223.9 11227.3 11228.3 11248.3 4389.7 4425.8 4509.9 4538.1 4566.2 9603.1 9617.9 9624.9 9625.5 9634.1 4939.7 4945.1 4947.4 4948.3 4952.1 3714.4 3764.1 4036.5 4485.0 4487.8 3751.9 3804.4 3854.2 3874.8 3913.0 1186.2 1222.3 1227.9 1236.1 1238.5 737.6 743.5 746.3 778.2 802.4 5366.6 5453.2 5475.2 5519.4 5631.0 412.5 414.9 419.7 422.3 432.3 3592.4 3670.5 3687.3 3704.0 3767.3 9452.8 9829.1 9928.8 9978.9 9994.7 9902.0 10006.1 10018.0 10023.6 10058.4 7624.6 7638.3 7682.0 7713.3 7714.5 5444.8 5457.5 5491.6 5529.8 5613.3 9282.2 9430.5 9436.7 9443.5 9530.7 8425.8 9094.3 9198.5 9636.8 9661.7 6918.7 7181.7 7286.2 7287.3 7287.8 7669.2 7717.1 7772.8 7776.2 7796.8 6882.6 6888.0 6988.9 7072.6 7244.4 6805.6 6837.0 6925.1 6983.8 7095.3 6525.8 6629.1 6632.6 6632.8 6633.5 4377.6 4381.5 4438.1 4507.3 4520.5 4527.4 4542.6 4612.4 4685.4 4769.5 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | [#4] prototype.html Cycles:180 Average:4234.52 Median:4847.53 stddev:1196.67 (24.7%) stddev-sans-first:1163.17 04:36:44 INFO - PROCESS | 23023 | Values: 308.0 313.6 315.1 319.6 320.5 4880.0 4910.5 4911.5 4918.5 4922.7 4790.9 4827.8 4860.9 4895.3 4895.6 3674.4 3739.3 3790.5 3846.8 3857.6 3839.6 3847.8 3850.5 3857.4 3873.3 3807.3 3826.7 3839.3 3841.2 3857.7 3651.9 3741.4 3849.4 3856.9 3865.2 3396.5 3423.4 3433.5 3439.5 3468.0 4717.7 4746.9 4749.3 4764.7 4764.8 4160.9 4420.5 4421.6 4429.0 4440.5 5051.2 5052.9 5068.5 5082.3 5092.3 3783.4 3785.8 3787.8 3791.0 3814.0 5216.4 5267.4 5336.9 5356.8 5367.2 3807.8 3821.8 3831.1 3832.3 3834.8 3879.9 3895.4 3896.5 3911.4 3916.4 4358.9 4383.7 4388.4 4409.1 4411.6 4901.5 5011.9 5024.1 5067.5 5072.9 1334.0 1346.7 1352.9 1354.6 1358.3 911.9 938.8 948.5 960.4 964.1 4250.7 4325.2 4460.3 4472.8 4493.3 5155.0 5191.7 5207.5 5213.4 5214.7 4460.8 4466.8 4467.7 4482.7 4488.5 2637.3 2703.6 2715.9 2717.9 2744.4 4823.4 4842.2 4846.2 4874.3 4888.9 5049.4 5272.8 5277.4 5300.3 5305.6 4848.8 4852.6 4869.3 4881.6 4896.5 5215.2 5221.2 5225.9 5242.2 5255.6 4947.2 5038.9 5098.7 5099.0 5102.7 4881.5 4890.5 4891.5 4905.9 4916.1 3814.6 3830.6 3856.4 3879.3 3892.9 5006.8 5244.0 5265.2 5266.6 5280.1 4961.7 4998.5 5004.3 5009.9 5032.6 4992.3 4999.4 5000.7 5000.8 5044.7 4734.1 4929.4 4939.5 4940.3 4961.8 4888.4 4977.7 5072.1 5087.4 5105.2 4991.2 4994.2 5010.0 5052.7 5054.0 04:36:44 INFO - PROCESS | 23023 | 04:36:44 INFO - PROCESS | 23023 | [#5] yui.html Cycles:180 Average:18589.24 Median:24348.96 stddev:27901.78 (114.6%) stddev-sans-first:27947.49 04:36:44 INFO - PROCESS | 23023 | Values: 636.9 642.4 670.8 725.4 750.8 12130.6 12951.7 12966.2 12988.7 12999.1 12993.7 13097.3 13137.7 13156.1 13166.1 620.8 635.4 759.7 761.0 783.4 775.5 786.6 786.8 788.1 788.2 845.3 846.2 846.3 846.7 847.5 660.9 803.1 830.9 837.4 838.5 609.0 609.4 611.0 612.8 613.5 13113.1 13144.7 13146.9 13156.0 13173.9 12252.3 12638.4 12781.6 13032.0 13534.3 11512.0 11994.8 12003.5 12137.5 12850.3 4134.1 4148.6 4190.7 4236.5 4298.9 64180.7 64212.4 64892.4 65644.7 66808.7 10142.7 10203.6 10327.4 10390.1 10454.8 17955.8 18188.0 18191.7 18195.8 18215.9 6587.8 6639.7 6673.2 6864.3 6977.8 3654.7 3700.5 3708.4 3737.8 3764.1 2772.8 2832.4 2855.9 2872.3 2874.0 500.2 504.8 510.2 510.8 513.5 466.2 492.5 492.9 495.7 498.5 8234.3 8433.4 8847.3 8922.8 9033.7 493.9 514.5 530.3 580.1 608.0 3978.7 4048.5 4113.7 4217.1 4330.6 100156.5 108931.0 111445.1 112085.0 113249.0 91443.0 91859.0 92110.0 92470.5 100436.0 50321.2 83000.6 86757.0 87296.0 87313.0 8139.8 8186.0 8331.5 8375.0 8549.5 33864.8 39895.8 46190.5 46353.3 48231.8 64846.7 65641.0 70685.0 71142.6 71847.0 10122.7 10588.4 10718.7 10753.3 10836.1 11280.3 11706.7 11784.4 11876.3 12260.5 10706.6 10944.4 11309.6 11347.2 11443.0 10528.4 11033.9 11247.4 11682.2 11889.0 10021.9 10183.7 10245.6 10516.6 10603.4 9156.2 9497.0 9518.5 9650.9 9774.8 8674.6 8731.6 8794.3 8798.6 9053.2 04:36:44 INFO - PROCESS | 23023 | -------- Summary: end -------- 04:36:44 INFO - PROCESS | 23023 | 04:36:45 INFO - TEST-INFO | 23023: exit 0 04:36:45 INFO - TEST-OK | dromaeo_css | took 1119856ms 04:36:45 INFO - TEST-START | kraken 04:36:45 INFO - operating with platform_type : linux_ 04:36:45 INFO - Initialising browser for kraken test... 04:36:45 INFO - TEST-INFO | started process 23206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoVvrBs/profile http://localhost:53746/getInfo.html) 04:36:49 INFO - PROCESS | 23206 | JavaScript error: , line 0: uncaught exception: undefined 04:36:49 INFO - PROCESS | 23206 | __metrics Screen width/height:1600/1200 04:36:49 INFO - PROCESS | 23206 | colorDepth:24 04:36:49 INFO - PROCESS | 23206 | Browser inner width/height: 1024/697 04:36:49 INFO - PROCESS | 23206 | __metrics 04:36:50 INFO - PROCESS | 23206 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:36:50 INFO - PROCESS | 23206 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:36:51 INFO - TEST-INFO | 23206: exit 0 04:36:51 INFO - Browser initialized. 04:36:51 INFO - Running cycle 1/1 for kraken test... 04:36:51 INFO - TEST-INFO | started process 23284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoVvrBs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:37:03 INFO - PROCESS | 23284 | RSS: Main: 159944704 04:37:03 INFO - PROCESS | 23284 | 04:37:06 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53746/tests/kraken/driver.html?testName=audio-beat-detection) 04:37:07 INFO - PROCESS | 23284 | RSS: Main: 215441408 04:37:07 INFO - PROCESS | 23284 | 04:37:10 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53746/tests/kraken/driver.html?testName=audio-dft) 04:37:10 INFO - PROCESS | 23284 | RSS: Main: 193835008 04:37:10 INFO - PROCESS | 23284 | 04:37:13 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53746/tests/kraken/driver.html?testName=audio-fft) 04:37:14 INFO - PROCESS | 23284 | RSS: Main: 273166336 04:37:14 INFO - PROCESS | 23284 | 04:37:16 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53746/tests/kraken/driver.html?testName=audio-oscillator) 04:37:17 INFO - PROCESS | 23284 | RSS: Main: 181727232 04:37:17 INFO - PROCESS | 23284 | 04:37:19 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53746/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:37:20 INFO - PROCESS | 23284 | RSS: Main: 186826752 04:37:20 INFO - PROCESS | 23284 | 04:37:23 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53746/tests/kraken/driver.html?testName=imaging-darkroom) 04:37:24 INFO - PROCESS | 23284 | RSS: Main: 222326784 04:37:24 INFO - PROCESS | 23284 | 04:37:27 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53746/tests/kraken/driver.html?testName=imaging-desaturate) 04:37:28 INFO - PROCESS | 23284 | RSS: Main: 216670208 04:37:28 INFO - PROCESS | 23284 | 04:37:30 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53746/tests/kraken/driver.html?testName=json-parse-financial) 04:37:31 INFO - PROCESS | 23284 | RSS: Main: 220340224 04:37:31 INFO - PROCESS | 23284 | 04:37:33 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53746/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:37:33 INFO - PROCESS | 23284 | RSS: Main: 185925632 04:37:33 INFO - PROCESS | 23284 | 04:37:35 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:37:36 INFO - PROCESS | 23284 | RSS: Main: 191062016 04:37:36 INFO - PROCESS | 23284 | 04:37:38 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:37:39 INFO - PROCESS | 23284 | RSS: Main: 196124672 04:37:39 INFO - PROCESS | 23284 | 04:37:41 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:37:42 INFO - PROCESS | 23284 | RSS: Main: 190820352 04:37:42 INFO - PROCESS | 23284 | 04:37:45 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:37:46 INFO - PROCESS | 23284 | RSS: Main: 194232320 04:37:46 INFO - PROCESS | 23284 | 04:37:47 INFO - PROCESS | 23284 | Cycle 1(1): loaded http://localhost:53746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53746/tests/kraken/driver.html?testName=ai-astar) 04:37:48 INFO - PROCESS | 23284 | RSS: Main: 244908032 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | __start_tp_report 04:37:48 INFO - PROCESS | 23284 | _x_x_mozilla_page_load 04:37:48 INFO - PROCESS | 23284 | _x_x_mozilla_page_load_details 04:37:48 INFO - PROCESS | 23284 | |i|pagename|runs| 04:37:48 INFO - PROCESS | 23284 | |0;ai-astar;105;114;116;113;114;113;112;112;113;113 04:37:48 INFO - PROCESS | 23284 | |1;audio-beat-detection;153;120;125;180;127;126;152;121;165;122 04:37:48 INFO - PROCESS | 23284 | |2;audio-dft;160;162;161;160;161;161;163;160;161;160 04:37:48 INFO - PROCESS | 23284 | |3;audio-fft;76;78;76;125;76;83;74;75;118;75 04:37:48 INFO - PROCESS | 23284 | |4;audio-oscillator;85;85;129;85;85;85;124;85;85;132 04:37:48 INFO - PROCESS | 23284 | |5;imaging-gaussian-blur;118;118;117;117;117;118;118;126;117;117 04:37:48 INFO - PROCESS | 23284 | |6;imaging-darkroom;147;146;147;146;147;147;147;146;146;147 04:37:48 INFO - PROCESS | 23284 | |7;imaging-desaturate;96;96;96;96;96;96;96;96;96;96 04:37:48 INFO - PROCESS | 23284 | |8;json-parse-financial;63;61;62;63;63;63;63;63;62;61 04:37:48 INFO - PROCESS | 23284 | |9;json-stringify-tinderbox;65;65;66;66;66;66;65;66;67;66 04:37:48 INFO - PROCESS | 23284 | |10;stanford-crypto-aes;79;79;78;78;78;79;78;79;79;83 04:37:48 INFO - PROCESS | 23284 | |11;stanford-crypto-ccm;136;127;131;139;135;129;126;126;166;129 04:37:48 INFO - PROCESS | 23284 | |12;stanford-crypto-pbkdf2;168;163;165;167;167;165;171;169;171;166 04:37:48 INFO - PROCESS | 23284 | |13;stanford-crypto-sha256-iterative;67;66;69;69;66;68;68;67;66;67 04:37:48 INFO - PROCESS | 23284 | __end_tp_report 04:37:48 INFO - PROCESS | 23284 | __start_cc_report 04:37:48 INFO - PROCESS | 23284 | _x_x_mozilla_cycle_collect,1906 04:37:48 INFO - PROCESS | 23284 | __end_cc_report 04:37:48 INFO - PROCESS | 23284 | __startTimestamp1469878668080__endTimestamp 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | ------- Summary: start ------- 04:37:48 INFO - PROCESS | 23284 | Number of tests: 14 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:2.88 (2.5%) stddev-sans-first:1.22 04:37:48 INFO - PROCESS | 23284 | Values: 105.0 114.0 116.0 113.0 114.0 113.0 112.0 112.0 113.0 113.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:139.50 stddev:21.62 (15.5%) stddev-sans-first:22.33 04:37:48 INFO - PROCESS | 23284 | Values: 153.0 120.0 125.0 180.0 127.0 126.0 152.0 121.0 165.0 122.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 04:37:48 INFO - PROCESS | 23284 | Values: 160.0 162.0 161.0 160.0 161.0 161.0 163.0 160.0 161.0 160.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#3] audio-fft Cycles:10 Average:85.60 Median:76.00 stddev:19.16 (25.2%) stddev-sans-first:20.00 04:37:48 INFO - PROCESS | 23284 | Values: 76.0 78.0 76.0 125.0 76.0 83.0 74.0 75.0 118.0 75.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:85.00 stddev:21.02 (24.7%) stddev-sans-first:21.76 04:37:48 INFO - PROCESS | 23284 | Values: 85.0 85.0 129.0 85.0 85.0 85.0 124.0 85.0 85.0 132.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:118.00 stddev:2.75 (2.3%) stddev-sans-first:2.92 04:37:48 INFO - PROCESS | 23284 | Values: 118.0 118.0 117.0 117.0 117.0 118.0 118.0 126.0 117.0 117.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:37:48 INFO - PROCESS | 23284 | Values: 147.0 146.0 147.0 146.0 147.0 147.0 147.0 146.0 146.0 147.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 04:37:48 INFO - PROCESS | 23284 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.00 stddev:0.84 (1.3%) stddev-sans-first:0.87 04:37:48 INFO - PROCESS | 23284 | Values: 63.0 61.0 62.0 63.0 63.0 63.0 63.0 63.0 62.0 61.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 04:37:48 INFO - PROCESS | 23284 | Values: 65.0 65.0 66.0 66.0 66.0 66.0 65.0 66.0 67.0 66.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.00 stddev:1.49 (1.9%) stddev-sans-first:1.58 04:37:48 INFO - PROCESS | 23284 | Values: 79.0 79.0 78.0 78.0 78.0 79.0 78.0 79.0 79.0 83.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#11] stanford-crypto-ccm Cycles:10 Average:134.40 Median:133.00 stddev:11.96 (9.0%) stddev-sans-first:12.68 04:37:48 INFO - PROCESS | 23284 | Values: 136.0 127.0 131.0 139.0 135.0 129.0 126.0 126.0 166.0 129.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.50 stddev:2.62 (1.6%) stddev-sans-first:2.76 04:37:48 INFO - PROCESS | 23284 | Values: 168.0 163.0 165.0 167.0 167.0 165.0 171.0 169.0 171.0 166.0 04:37:48 INFO - PROCESS | 23284 | 04:37:48 INFO - PROCESS | 23284 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.16 (1.7%) stddev-sans-first:1.22 04:37:48 INFO - PROCESS | 23284 | Values: 67.0 66.0 69.0 69.0 66.0 68.0 68.0 67.0 66.0 67.0 04:37:48 INFO - PROCESS | 23284 | -------- Summary: end -------- 04:37:48 INFO - PROCESS | 23284 | 04:37:49 INFO - TEST-INFO | 23284: exit 0 04:37:49 INFO - TEST-OK | kraken | took 63742ms 04:37:49 INFO - SUITE-END | took 1183s 04:37:49 INFO - Completed test suite (00:19:43) 04:37:49 INFO - javascript benchmark 04:37:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2653.4958357911996, 2658.9069382571615, 2658.9762941267613, 2694.5419278722616, 2700.9459810803783, 4691.812327506896, 4757.405324334458, 4809.086273608012, 4851.660383773137, 4874.317595536623, 4789.090072886149, 4794.976025119879, 4805.663603547744, 4819.903601927959, 4856.684315519488, 4337.219300525904, 4366.759828209447, 4422.447194100738, 4486.147631949935, 4512.503624601308, 4448.777561121947, 4480.260756975095, 4486.057927835159, 4497.257952437843, 4516.458025036984, 4326.6754993375625, 4368.191884501346, 4379.0585024219945, 4379.890502737425, 4383.3863259143745, 4345.152695224432, 4349.739015659071, 4362.105768317488, 4420.734755914656, 4427.40230068943, 4369.6722745794195, 4369.868903932887, 4394.4946331172005, 4406.471223453174, 4420.602145806893, 4666.883327916796, 4774.856754297377, 4838.2016967200725, 4839.249916262985, 4853.538913803183, 5061.772220250097, 5100.923486147711, 5146.9485305146845, 5150.381954165487, 5204.505571970694, 5176.352955880515, 5178.97410512945, 5199.610029247821, 5238.92141617876, 5250.816221432232, 4680.251159814414, 4745.217039188564, 4847.95152048482, 4886.413630364379, 4892.241702536113, 5223.242629818655, 5224.268602395668, 5273.815416460406, 5293.947060529423, 5302.284191634157, 5001.64988450805, 5010.574101201366, 5024.598032157419, 5063.974680126576, 5065.6200784941275, 5027.120253955543, 5071.41678706946, 5121.411037730634, 5170.276161337416, 5189.143791274473, 5092.694438333712, 5114.43741188469, 5118.564921981673, 5159.509846564571, 5163.586913046947, 5082.085224659523, 5096.992417901847, 5098.694078355311, 5099.617528685363, 5152.536271735562, 4165.250254954138, 4199.664026877904, 4328.978355108205, 4329.047609525899, 4373.300271956536, 3740.9625903741157, 3772.641599048086, 3792.222594368203, 3800.087978885103, 3840.577536470986, 4393.20922234001, 4399.64802815774, 4405.07493426384, 4437.977810110993, 4440.111977604492, 5097.133487307166, 5103.795848166032, 5154.871128221749, 5157.097507936096, 5157.793688252428, 4227.788610569459, 4343.826246950087, 4367.192069467095, 4370.890727731832, 4403.097365040159, 4645.303204519281, 4647.674662773641, 4657.86026419208, 4674.322223277673, 4694.624430045588, 4791.065742180242, 4791.880202994953, 4808.687435316763, 4814.253790662453, 4850.4179498459, 4995.750212489288, 5118.3857937046305, 5154.7938082476285, 5164.32863727728, 5169.53474187325, 4807.519248075165, 4832.130216560912, 4834.468208497133, 4836.322914791862, 4856.878578035577, 5105.132127538253, 5108.438071812172, 5110.1568241239775, 5114.488551145005, 5119.897602047864, 5003.299538064601, 5134.922976155286, 5142.64001519905, 5175.689458632494, 5213.817516386908, 5066.746662666926, 5066.923996139987, 5071.0, 5072.54347108762, 5090.847274581768, 5054.974725126351, 5056.873578160576, 5059.215821547666, 5066.0, 5096.05722941257, 4818.180909245507, 4922.187839006667, 4964.950350496593, 4980.526849949249, 4989.55094041538, 5078.695278283315, 5082.237664350377, 5086.135356989246, 5092.7199004053655, 5122.1548444507725, 4952.479989601185, 4952.77712502931, 4953.950460495349, 4966.106100901872, 4987.376577927759], "value": 4715.2457841793375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.9661514941596, 577.4485366475014, 579.3829571506343, 584.3922320786382, 597.6563476001301, 39655.40516892245, 41054.178916421726, 41164.58835411645, 41275.58724412755, 44493.0, 41136.0, 41533.79233103838, 41608.58391416085, 41615.37576936341, 41720.58279417205, 7429.554226746385, 7733.651985660631, 7880.369570434352, 7883.2510911463605, 9606.903930960705, 9703.61185552577, 10052.69841904744, 10084.243681723863, 10085.041921017528, 10095.646652367168, 9668.419894806335, 9685.854712179324, 9689.51552422376, 9716.368436051669, 9724.5899344595, 10071.69784906454, 10103.191744660446, 10103.292769506137, 10131.594736210616, 10134.138598219124, 9946.0, 10011.59953601855, 10011.949940250252, 10019.699409017741, 10023.498825058718, 39747.80126099351, 39840.40239396382, 39920.60079399198, 40109.19781604381, 40125.0, 3049.1005153479555, 3146.638136614296, 3326.334733053399, 3670.9632903671154, 3869.032741814546, 29657.40685186284, 29828.701712983027, 30439.391212175855, 31754.0, 32494.18764530882, 3498.8892415106325, 3512.8067956262394, 3552.7335449841107, 3555.591107022674, 3609.2781443711365, 272319.0, 274460.0, 274810.0, 274904.0, 275283.6235818828, 56378.43621563814, 56813.0, 56936.71531642315, 57061.71469142711, 57089.71455142698, 33650.49524257138, 34388.0, 34709.65290347115, 36038.8198059008, 36307.818460908056, 14466.0, 14486.927565362317, 14497.492587759378, 14502.202378869053, 14508.202048887413, 10740.94629526863, 11002.669919902415, 11005.119590432747, 11258.493367798468, 12279.263244205376, 17768.91115544414, 18312.542186445444, 18322.725159122623, 18711.941686414484, 19075.236990520225, 6641.0, 6761.8647627047185, 6785.219699734594, 6864.5881247124335, 6986.9650651746415, 689.5138927056413, 740.8851627997561, 808.2887059387701, 864.5979619476848, 890.3722875372847, 28537.354543821366, 29005.419891602472, 29072.854635726686, 29565.704342956724, 29638.55542166869, 886.6143227695973, 950.7147855643279, 961.7932144588947, 968.1577027985697, 997.6358629100326, 14747.262636868114, 15386.538403847902, 15474.84525154734, 15715.449959251371, 15734.213289335488, 402806.0, 405565.0, 406768.0, 407153.0, 409676.0, 48955.755221224375, 49434.752826235635, 49453.25820112701, 49498.0, 49560.0, 1021.1830535571513, 1074.6883403812963, 1096.0245381610373, 1123.0678536814423, 1124.2411372323813, 28274.71725282803, 29398.706012939598, 29567.26081847971, 30200.0, 30247.848760756937, 35842.64157358393, 36246.63753362433, 36249.27501450009, 36262.27475450529, 36282.8185859069, 313640.0, 313806.0, 314143.0, 314376.0, 314656.0, 23474.413139671644, 24422.02311907504, 24483.265502034967, 25372.746272537777, 25718.614220787076, 39467.80266098651, 40090.599094008685, 40231.19537609173, 40578.594214057484, 42317.67252801125, 33696.83151584226, 36459.81770091133, 36606.816965915, 37288.6271137296, 37542.812285938395, 36528.26943461063, 37266.0, 37453.81273093617, 38398.616013839506, 38672.0, 36304.09239769027, 37031.8148409267, 37112.07219819526, 37388.0, 37563.81218093892, 32188.0, 34348.65651343555, 34665.306693866485, 34780.304393912484, 34783.82608086943, 25569.48861022806, 25648.48703025892, 25651.743482565682, 25732.742672573033, 25899.741002589733], "value": 17980.15216043226, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.0811593913793, 364.0643546086557, 375.7332294071209, 383.4306055507571, 393.75783892905855, 12890.935545322272, 15971.201439928016, 16708.498745037654, 18213.18040688169, 18330.633387332244, 17608.735868961976, 17839.375621853236, 17956.730649040273, 18030.5492362691, 18144.09279536021, 4699.248120300753, 5072.898542029157, 5116.539511443969, 5121.052605268037, 5122.206058061007, 3735.3276410246144, 5029.547340739333, 5111.13110771168, 5545.722713864311, 5850.912236316459, 5840.591158618899, 5848.67832269225, 5854.853628659275, 5858.7949421770245, 5863.618864773776, 5653.406392328788, 5790.7973220937065, 5834.737436815353, 5835.766569337221, 5836.445537673914, 5186.0, 5187.247849061902, 5189.42916279209, 5191.0, 5196.29849970253, 21065.0, 21331.146754129815, 21938.232161874257, 21951.67072493898, 22088.668669969964, 1861.255497800877, 1868.1966754295647, 1885.5286178455385, 1897.5066482714594, 2004.0480771633358, 12941.935290323583, 13659.590212293648, 13937.233452160128, 14278.143311401349, 14457.204853733041, 1758.1209395302349, 1827.2782251010776, 1835.7613510243716, 1841.8802777819399, 1846.796852346241, 70671.64664176697, 72434.91347629842, 72605.0, 74156.62921685411, 75338.62330688312, 9640.469774162488, 9869.753256168653, 9959.203263738884, 10298.536565854554, 10349.844752328721, 13914.165150091027, 13918.373673184731, 13928.651783705489, 13932.791008134885, 13943.860561394256, 7438.962805185939, 7439.218882017419, 7798.727044553413, 7882.093559240646, 7993.840123197503, 7197.725163405096, 7321.341079302888, 7549.471536992468, 7713.65288562016, 7948.920510794934, 6255.906161407492, 6333.904991425133, 6350.968245158745, 6356.0, 6508.967455162789, 1767.7878654561534, 1804.4767017565023, 1831.4047934421367, 1837.4946889605465, 1864.4499872537633, 764.1556080530934, 768.6080099149362, 783.4437549340032, 801.9278264956182, 860.6514361683528, 17068.146592670564, 17612.647747045245, 17795.911020445074, 17973.460796176136, 18307.0, 823.197382552007, 824.6371596497522, 830.2154464031553, 836.2766207230824, 862.1550880137376, 9682.56428460721, 10418.895811041944, 10504.593329578336, 10568.841467377995, 10591.205659575562, 388160.0, 390006.0, 397377.0, 409146.0, 410257.0, 43279.78360108179, 73671.26328736752, 74354.62822685852, 75804.0, 76964.61517692376, 886.2334081019872, 889.7375274294099, 905.4657751926395, 938.1275413865171, 942.8403064231035, 15811.13038782878, 15994.920025400264, 16322.918385407997, 16446.177691115634, 16724.749128762836, 38905.6109438902, 39903.40144897771, 40356.59643403528, 41129.97175070647, 41363.7931810339, 57654.4234557649, 57805.0, 58433.0, 58825.411745882, 59401.702991484766, 6745.426638735762, 6885.036094946811, 6957.095577574883, 7190.7483238086415, 7331.41348692114, 8965.0, 11832.467538960595, 12019.75960480768, 12209.267443953391, 12575.0, 10055.69832905014, 10176.0, 10219.182465402899, 10717.0, 10736.194785391282, 5725.34158571753, 10024.498775061364, 10629.25595208328, 10752.08607268391, 10789.190810689073, 9867.40795552269, 10007.749806254902, 10343.586256549654, 10561.683149505527, 10761.784764304513, 7028.543144695578, 7197.496175267681, 7301.780946571611, 7573.8106547334555, 7648.579328137006, 9372.531373431164, 10483.895161048293, 10570.2600817942, 10799.838002430128, 10810.945945270538], "value": 8479.452314071093, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1275.0246061762748, 1391.9095258808177, 1430.6924011337562, 1508.253414559793, 1710.2816816936893, 6949.409300209482, 7601.2018738032475, 7919.7624071277805, 7920.1683823198455, 7935.2461516155945, 7555.811104722385, 7684.423668224891, 7721.961390193055, 7900.367970562355, 7911.36709063275, 2169.0456199272307, 2214.0258286354033, 2215.634420320645, 2216.92240771573, 2217.6562632791943, 2161.6001039807607, 2191.9890400548056, 2208.182972300251, 2209.0611490116717, 2220.578090162864, 2180.7056047433553, 2184.170015394148, 2193.035064571591, 2195.2316689158824, 2245.2478419729327, 2184.497565559914, 2186.8906554672203, 2191.649336106231, 2193.8354623403307, 2194.703714998471, 2067.8345732341377, 2089.122568521225, 2095.413284280404, 2102.2431924507164, 2109.683547467877, 7789.182135875708, 7992.160823113548, 8005.359571234288, 8057.073436554812, 8059.314958228558, 4685.367475390813, 4900.509949005107, 4909.975450122763, 4916.950830491685, 4920.0, 2941.617589713345, 3043.6195475565555, 3081.229692576856, 3107.192130046182, 3112.2375018120706, 935.6257497001186, 941.204682043672, 946.9526523673788, 970.9805803883883, 985.5515740338129, 10997.0, 11223.943880280627, 11227.270227435272, 11228.270162439416, 11248.268862523992, 4389.714668546534, 4425.778711064434, 4509.88725281864, 4538.092381523708, 4566.246569316026, 9603.135717785433, 9617.903820961841, 9624.855627165598, 9625.51872406377, 9634.084761947744, 4939.728314942713, 4945.134601444733, 4947.381577302837, 4948.282499037691, 4952.108620448354, 3714.3685573452576, 3764.0966168119453, 4036.455078564415, 4485.0, 4487.775611219426, 3751.8874433767032, 3804.4293355996824, 3854.190619969838, 3874.845006199777, 3913.0413048802657, 1186.2408058842348, 1222.3093951917317, 1227.9017678585692, 1236.1470585296117, 1238.5417395563518, 737.6238118559573, 743.4535616321999, 746.2649290448903, 778.1906816910463, 802.4423025996877, 5366.597505187126, 5453.209284653703, 5475.178723191553, 5519.448055194529, 5631.042722737145, 412.53177643374386, 414.85480081971673, 419.67684882640197, 422.3095239283793, 432.3061486314483, 3592.3533763922746, 3670.504481894963, 3687.262547490459, 3703.9629603704157, 3767.2653832502397, 9452.763680908032, 9829.115379615869, 9928.751781205528, 9978.900210997943, 9994.700158995242, 9902.0, 10006.09945104944, 10018.0, 10023.599056037678, 10058.446785426871, 7624.58064806441, 7638.312551870358, 7682.0397450318715, 7713.305802477804, 7714.4599878008, 5444.782208711607, 5457.481539253765, 5491.588130890119, 5529.751161197675, 5613.326400831927, 9282.164605185295, 9430.528473576156, 9436.669716559887, 9443.527823608929, 9530.714078577654, 8425.83148337042, 9094.27245820346, 9198.482250428604, 9636.807263854544, 9661.661841835783, 6918.654067296716, 7181.676824542804, 7286.16209135935, 7287.344139027513, 7287.8178045547165, 7669.233076692286, 7717.073951125901, 7772.7668169954995, 7776.183500732568, 7796.84406311882, 6882.552634078769, 6888.035675005403, 6988.930110698828, 7072.646367681493, 7244.384227340735, 6805.591664500146, 6837.0, 6925.134358205224, 6983.75556855528, 7095.254998225319, 6525.771597994048, 6629.138212032598, 6632.568883022588, 6632.801015969529, 6633.535652504472, 4377.606015458624, 4381.518033016302, 4438.134563760036, 4507.256302710016, 4520.480144783261, 4527.38880251175, 4542.613877820422, 4612.354270402214, 4685.437747470189, 4769.4753577107185], "value": 4158.6302157487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.96612372639015, 313.62835040477034, 315.12395540397694, 319.6483867745479, 320.4600248581141, 4879.951200487999, 4910.508949105097, 4911.459739428665, 4918.458969513351, 4922.729249891255, 4790.9041819163685, 4827.758612069391, 4860.8541743747655, 4895.265710143481, 4895.5838753705975, 3674.419999400257, 3739.289534988357, 3790.54513458384, 3846.76903390915, 3857.6305411578905, 3839.5584507781546, 3847.8460861565504, 3850.4801851750076, 3857.4213867919757, 3873.341531939563, 3807.257584770971, 3826.6747326477284, 3839.2705385976574, 3841.2125514269646, 3857.6661120022263, 3651.908702282438, 3741.38267185914, 3849.441830934513, 3856.8842934712, 3865.1689886674217, 3396.490526421032, 3423.3910062270693, 3433.4892647235138, 3439.5356626855305, 3468.0, 4717.693349932243, 4746.908211111429, 4749.335093086971, 4764.690295130806, 4764.761761911891, 4160.855764664711, 4420.46954365475, 4421.585092770315, 4429.0, 4440.534623574245, 5051.242313652945, 5052.9242061369105, 5068.493150684939, 5082.339295891547, 5092.261622064817, 3783.4135708964877, 3785.772853628791, 3787.8295476703615, 3791.0, 3814.0, 5216.426193118792, 5267.446918073624, 5336.93261347732, 5356.785728570852, 5367.194920761878, 3807.828647710804, 3821.808909554567, 3831.099691572478, 3832.291026160169, 3834.827432765532, 3879.8836034919, 3895.35726605107, 3896.493455850721, 3911.3546264865977, 3916.3538016227003, 4358.934615980763, 4383.671224658164, 4388.363687265392, 4409.1181763646755, 4411.602955733999, 4901.534354236342, 5011.924821127686, 5024.145895197825, 5067.543921047123, 5072.923906141411, 1333.986170510419, 1346.7171893902193, 1352.932353382327, 1354.6477915741978, 1358.3479929633634, 911.878389580806, 938.784079661668, 948.5257371314343, 960.3949511807516, 964.0889359555154, 4250.702450828412, 4325.243082460557, 4460.330950357472, 4472.821087156477, 4493.326001099861, 5154.974225128851, 5191.740412979336, 5207.505286997729, 5213.426523082458, 5214.687118772886, 4460.77696115193, 4466.821327146943, 4467.6597020893605, 4482.731036137842, 4488.461384633865, 2637.31429828246, 2703.5539136042335, 2715.8506282154276, 2717.8641067946523, 2744.4493860968887, 4823.397075365579, 4842.15262329091, 4846.248831431134, 4874.341963834908, 4888.853334399973, 5049.368828896388, 5272.815451459181, 5277.393099793496, 5300.257963885059, 5305.628605997543, 4848.80604775805, 4852.611791056707, 4869.269609558523, 4881.609471242363, 4896.510348965075, 5215.191645294985, 5221.242919776535, 5225.895482090261, 5242.18746094356, 5255.553277971415, 4947.233178857283, 5038.949610503848, 5098.694078355311, 5098.97450512745, 5102.729285467931, 4881.536254056001, 4890.462049174659, 4891.486393928586, 4905.926411103907, 4916.06594746997, 3814.5994670560303, 3830.616938306147, 3856.421536769507, 3879.282332768447, 3892.8832135035996, 5006.774695138654, 5243.973780131075, 5265.183896496049, 5266.631335806457, 5280.128778751463, 4961.727105009115, 4998.525140111684, 5004.274380214922, 5009.918899876622, 5032.572231360376, 4992.251162325535, 4999.400071991385, 5000.749962501787, 5000.799968001239, 5044.672096313728, 4734.124187025273, 4929.433115191799, 4939.48135445773, 4940.333055037668, 4961.776720047678, 4888.362398596368, 4977.726225057657, 5072.137736584716, 5087.364079490064, 5105.2086926526445, 4991.179983309418, 4994.200927851526, 5009.998000400007, 5052.671576347526, 5054.0], "value": 3862.906143766118, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.866258085802, 642.3672682407828, 670.8054664147397, 725.4414101142121, 750.8460765543065, 12130.636080917575, 12951.74096518069, 12966.222026678393, 12988.74022519549, 12999.090063695558, 12993.675158121028, 13097.279649619268, 13137.67155821105, 13156.14485058473, 13166.144200626952, 620.7982405718137, 635.4027214418455, 759.654357267442, 760.9771706848803, 783.4280974888335, 775.5153029356652, 786.6066966516742, 786.7954331873725, 788.113372455987, 788.2275370137269, 845.3364109174299, 846.2257034813139, 846.2764336492303, 846.6571038729325, 847.478800537668, 660.9140811694449, 803.1245941923273, 830.9252167304911, 837.351052933975, 838.4969018588829, 608.9695515224221, 609.4180058044557, 610.9572329936906, 612.8406614280275, 613.4938675592654, 13113.147645403018, 13144.6713832154, 13146.934265328708, 13156.0, 13173.934130329382, 12252.326122063283, 12638.368081595976, 12781.552645657357, 13032.0, 13534.25561594112, 11512.0, 11994.760104797855, 12003.519859205622, 12137.514499420013, 12850.293233872133, 4134.090500089976, 4148.585141485857, 4190.685698572589, 4236.533981262059, 4298.914021719579, 64180.67909660468, 64212.35787642157, 64892.35107648958, 65644.67177664129, 66808.66595667039, 10142.746431339128, 10203.642872499575, 10327.380357178594, 10390.116840068678, 10454.843177352346, 17955.820441795677, 18188.0, 18191.727124093148, 18195.81804181968, 18215.907045577307, 6587.769428069994, 6639.701213445406, 6673.1992160941, 6864.313568643096, 6977.755778547727, 3654.6710796028483, 3700.518932538753, 3708.3881159608363, 3737.8131093445763, 3764.0778009387877, 2772.8336299822076, 2832.3910359272445, 2855.914322570326, 2872.3250036241466, 2873.956890646642, 500.1622282676502, 504.78798904460217, 510.2168171856183, 510.8007876928001, 513.4608660906034, 466.1516040805701, 492.48781267481417, 492.94824043475637, 495.65799598277073, 498.461661405681, 8234.341252699769, 8433.367497437717, 8847.336449766293, 8922.821543569093, 9033.683821066232, 493.90121975605024, 514.531776083762, 530.3105962249138, 580.0921557762089, 607.9513638908877, 3978.7214894957074, 4048.4939382577177, 4113.650339721097, 4217.135487225111, 4330.631896288788, 100156.49921750344, 108931.0, 111445.09942151986, 112085.0, 113249.0, 91443.0, 91859.0, 92110.0, 92470.53764731268, 100435.99564004413, 50321.24518132231, 83000.58499707463, 86757.0, 87296.0, 87313.0, 8139.75580732579, 8186.0, 8331.541765202972, 8374.958125209336, 8549.487030778173, 33864.83067584646, 39895.80052099721, 46190.538094619966, 46353.304700428846, 48231.75884120557, 64846.675766620865, 65641.0, 70685.0, 71142.64428678031, 71847.0, 10122.746931326776, 10588.364698118137, 10718.73203169927, 10753.301035432673, 10836.078933290759, 11280.32318060919, 11706.70733231676, 11784.41077946082, 11876.346800926032, 12260.509579616715, 10706.571737130738, 10944.398058106563, 11309.604163854554, 11347.205695601559, 11443.0, 10528.42093684824, 11033.944830275797, 11247.381394023407, 11682.182247242612, 11889.0, 10021.899781002097, 10183.7454063649, 10245.59017639316, 10516.631917882838, 10603.41681207541, 9156.175944165057, 9496.952515237379, 9518.476483793458, 9650.903490965282, 9774.75563110928, 8674.571120086386, 8731.607077681392, 8794.29645628361, 8798.604062817061, 9053.230475409664], "value": 6563.658782619263, "unit": "score"}], "name": "dromaeo_css", "value": 6505.604393937692, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 116.0, 113.0, 114.0, 113.0, 112.0, 112.0, 113.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 125.0, 180.0, 127.0, 126.0, 152.0, 121.0, 165.0, 122.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 160.0, 161.0, 161.0, 163.0, 160.0, 161.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 125.0, 76.0, 83.0, 74.0, 75.0, 118.0, 75.0], "value": 85.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 129.0, 85.0, 85.0, 85.0, 124.0, 85.0, 85.0, 132.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 126.0, 117.0, 117.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0, 61.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0, 67.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 78.0, 78.0, 79.0, 78.0, 79.0, 79.0, 83.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 127.0, 131.0, 139.0, 135.0, 129.0, 126.0, 126.0, 166.0, 129.0], "value": 134.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 163.0, 165.0, 167.0, 167.0, 165.0, 171.0, 169.0, 171.0, 166.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 69.0, 66.0, 68.0, 68.0, 67.0, 66.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1533.1000000000001, "alertThreshold": 2.0}]} 04:37:49 INFO - Return code: 0 04:37:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:37:49 INFO - # TBPL SUCCESS # 04:37:49 INFO - Running post-action listener: _resource_record_post_action 04:37:49 INFO - [mozharness: 2016-07-30 11:37:49.369800Z] Finished run-tests step (success) 04:37:49 INFO - Running post-run listener: _resource_record_post_run 04:37:49 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 542461952; Read time: 0; Write time: 499692 04:37:49 INFO - TinderboxPrint: CPU usage
13.0% 04:37:49 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:37:49 INFO - TinderboxPrint: I/O write bytes / time
542,461,952 / 499,692 04:37:49 INFO - TinderboxPrint: CPU idle
8,333.2 (87.0%) 04:37:49 INFO - TinderboxPrint: CPU user
1,200.1 (12.5%) 04:37:49 INFO - TinderboxPrint: Swap in / out
0 / 0 04:37:49 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 256618496; Read time: 0; Write time: 251484 04:37:49 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 285843456; Read time: 0; Write time: 248208 04:37:50 INFO - Running post-run listener: _upload_blobber_files 04:37:50 INFO - Blob upload gear active. 04:37:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:37:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:37:50 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:37:50 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:37:50 INFO - (blobuploader) - INFO - Open directory for files ... 04:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:37:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:51 INFO - (blobuploader) - INFO - Done attempting. 04:37:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:37:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:56 INFO - (blobuploader) - INFO - Done attempting. 04:37:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:37:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:56 INFO - (blobuploader) - INFO - Done attempting. 04:37:56 INFO - (blobuploader) - INFO - Iteration through files over. 04:37:56 INFO - Return code: 0 04:37:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:37:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:37:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5689b0fa384e2b8cebf5bb8bf9276ad12041014aea6581efd9b6941dd50f956bee6068ab9a946994a1a5eca8480ac14df6bfda47e6ae9b6a94b90010458f4e65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1d2bff13acea047d16cabba77d18768a9c11c5d3d8f1510e6af2b933c06f936c99533c99dca5db4a11ad748746c40798c95bf67113dd732b091a8f99f92a6716", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f371644b90ab3b7b49d7fbbb6f94db521e29779a401fd4f112bdfc71116058398e8c8e9c180f13c402f05a08239cdde18331db880b1d828ae11122a54e87cb81"} 04:37:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:37:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:37:56 INFO - Contents: 04:37:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5689b0fa384e2b8cebf5bb8bf9276ad12041014aea6581efd9b6941dd50f956bee6068ab9a946994a1a5eca8480ac14df6bfda47e6ae9b6a94b90010458f4e65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1d2bff13acea047d16cabba77d18768a9c11c5d3d8f1510e6af2b933c06f936c99533c99dca5db4a11ad748746c40798c95bf67113dd732b091a8f99f92a6716", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f371644b90ab3b7b49d7fbbb6f94db521e29779a401fd4f112bdfc71116058398e8c8e9c180f13c402f05a08239cdde18331db880b1d828ae11122a54e87cb81"} 04:37:56 INFO - Running post-run listener: copy_logs_to_upload_dir 04:37:56 INFO - Copying logs to upload dir... 04:37:56 INFO - mkdir: /builds/slave/test/build/upload/logs 04:37:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.546222 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-30 04:37:56.768655) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 04:37:56.772401) ========= 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=4300f881736b422476174874000001bc-1469877434.507722-2103265578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5689b0fa384e2b8cebf5bb8bf9276ad12041014aea6581efd9b6941dd50f956bee6068ab9a946994a1a5eca8480ac14df6bfda47e6ae9b6a94b90010458f4e65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1d2bff13acea047d16cabba77d18768a9c11c5d3d8f1510e6af2b933c06f936c99533c99dca5db4a11ad748746c40798c95bf67113dd732b091a8f99f92a6716", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f371644b90ab3b7b49d7fbbb6f94db521e29779a401fd4f112bdfc71116058398e8c8e9c180f13c402f05a08239cdde18331db880b1d828ae11122a54e87cb81"} build_url:https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5689b0fa384e2b8cebf5bb8bf9276ad12041014aea6581efd9b6941dd50f956bee6068ab9a946994a1a5eca8480ac14df6bfda47e6ae9b6a94b90010458f4e65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1d2bff13acea047d16cabba77d18768a9c11c5d3d8f1510e6af2b933c06f936c99533c99dca5db4a11ad748746c40798c95bf67113dd732b091a8f99f92a6716", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f371644b90ab3b7b49d7fbbb6f94db521e29779a401fd4f112bdfc71116058398e8c8e9c180f13c402f05a08239cdde18331db880b1d828ae11122a54e87cb81"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 04:37:56.800803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:37:56.801149) ========= 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=4300f881736b422476174874000001bc-1469877434.507722-2103265578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:37:56.882873) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 04:37:56.883217) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 04:37:56.883524) ========= ========= Total master_lag: 0.19 =========