builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1463061965.47 results: success (0) buildid: 20160512043053 builduid: 46ef79d4eee54e17b5df5a611d0775f4 revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.472381) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.473107) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.473418) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.503323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.503645) ========= 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=0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 07:06:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 27.4M=0s 2016-05-12 07:06:05 (27.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115824 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.642316) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.642801) ========= 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=0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018148 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.702340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:05.702768) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --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=0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 07:06:05,875 truncating revision to first 12 chars 2016-05-12 07:06:05,875 Setting DEBUG logging. 2016-05-12 07:06:05,875 attempt 1/10 2016-05-12 07:06:05,875 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3f5e6079284?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 07:06:06,361 unpacking tar archive at: mozilla-central-c3f5e6079284/testing/mozharness/ program finished with exit code 0 elapsedTime=0.784108 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:06.500915) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:06.501259) ========= script_repo_revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:06.501706) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:06.502043) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 07:06:06.512532) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 54 secs) (at 2016-05-12 07:06:06.512993) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:06:06 INFO - MultiFileLogger online at 20160512 07:06:06 in /builds/slave/test 07:06:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:06:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:06:06 INFO - {'append_to_log': False, 07:06:06 INFO - 'base_work_dir': '/builds/slave/test', 07:06:06 INFO - 'blob_upload_branch': 'Firefox', 07:06:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:06:06 INFO - 'branch': 'Firefox', 07:06:06 INFO - 'buildbot_json_path': 'buildprops.json', 07:06:06 INFO - 'config_files': ('talos/linux_config.py',), 07:06:06 INFO - 'default_actions': ('clobber', 07:06:06 INFO - 'read-buildbot-config', 07:06:06 INFO - 'download-and-extract', 07:06:06 INFO - 'populate-webroot', 07:06:06 INFO - 'create-virtualenv', 07:06:06 INFO - 'install', 07:06:06 INFO - 'run-tests'), 07:06:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:06:06 INFO - 'download_minidump_stackwalk': True, 07:06:06 INFO - 'download_symbols': 'ondemand', 07:06:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:06:06 INFO - 'tooltool.py': '/tools/tooltool.py', 07:06:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:06:06 INFO - '/tools/misc-python/virtualenv.py')}, 07:06:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:06:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:06:06 INFO - 'installer_path': 'installer.exe', 07:06:06 INFO - 'log_level': 'info', 07:06:06 INFO - 'log_name': 'talos', 07:06:06 INFO - 'log_to_console': True, 07:06:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:06:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:06:06 INFO - 'opt_config_files': (), 07:06:06 INFO - 'pip_index': False, 07:06:06 INFO - 'sps_profile': False, 07:06:06 INFO - 'sps_profile_interval': 0, 07:06:06 INFO - 'suite': 'dromaeojs', 07:06:06 INFO - 'system_bits': '32', 07:06:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:06:06 INFO - 'title': 'talos-linux64-ix-017', 07:06:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:06:06 INFO - 'use_talos_json': True, 07:06:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:06:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:06:06 INFO - 'work_dir': 'build'} 07:06:06 INFO - ##### 07:06:06 INFO - ##### Running clobber step. 07:06:06 INFO - ##### 07:06:06 INFO - Running pre-action listener: _resource_record_pre_action 07:06:06 INFO - Running main action method: clobber 07:06:06 INFO - rmtree: /builds/slave/test/build 07:06:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:06:07 INFO - Running post-action listener: _resource_record_post_action 07:06:07 INFO - ##### 07:06:07 INFO - ##### Finished clobber step (success) 07:06:07 INFO - ##### 07:06:07 INFO - ##### 07:06:07 INFO - ##### Running read-buildbot-config step. 07:06:07 INFO - ##### 07:06:07 INFO - Running pre-action listener: _resource_record_pre_action 07:06:07 INFO - Running main action method: read_buildbot_config 07:06:07 INFO - Using buildbot properties: 07:06:07 INFO - { 07:06:07 INFO - "project": "", 07:06:07 INFO - "product": "firefox", 07:06:07 INFO - "script_repo_revision": "production", 07:06:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:06:07 INFO - "repository": "", 07:06:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:06:07 INFO - "buildid": "20160512043053", 07:06:07 INFO - "pgo_build": "True", 07:06:07 INFO - "basedir": "/builds/slave/test", 07:06:07 INFO - "buildnumber": 283, 07:06:07 INFO - "slavename": "talos-linux64-ix-017", 07:06:07 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:06:07 INFO - "platform": "ubuntu64_hw", 07:06:07 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:06:07 INFO - "branch": "mozilla-central", 07:06:07 INFO - "repo_path": "mozilla-central", 07:06:07 INFO - "revision": "c3f5e6079284a7b7053c41f05d0fe06ff031db03", 07:06:07 INFO - "stage_platform": "linux64-pgo", 07:06:07 INFO - "builduid": "46ef79d4eee54e17b5df5a611d0775f4", 07:06:07 INFO - "slavebuilddir": "test" 07:06:07 INFO - } 07:06:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:06:07 INFO - Running post-action listener: _resource_record_post_action 07:06:07 INFO - ##### 07:06:07 INFO - ##### Finished read-buildbot-config step (success) 07:06:07 INFO - ##### 07:06:07 INFO - ##### 07:06:07 INFO - ##### Running download-and-extract step. 07:06:07 INFO - ##### 07:06:07 INFO - Running pre-action listener: _resource_record_pre_action 07:06:07 INFO - Running main action method: download_and_extract 07:06:07 INFO - mkdir: /builds/slave/test/build/tests 07:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:07 INFO - https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:06:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:06:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:06:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:06:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:06:10 INFO - Downloaded 1361 bytes. 07:06:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:06:10 INFO - Using the following test package requirements: 07:06:10 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:06:10 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:06:10 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:06:10 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'jsshell-linux-x86_64.zip'], 07:06:10 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:06:10 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:06:10 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:06:10 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:06:10 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:06:10 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:06:10 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:10 INFO - https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:06:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:06:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:06:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:06:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:06:24 INFO - Downloaded 23904308 bytes. 07:06:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:06:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:06:25 INFO - Return code: 0 07:06:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:25 INFO - https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:06:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:06:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:06:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:06:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:06:30 INFO - Downloaded 23904308 bytes. 07:06:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:06:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:06:32 INFO - Return code: 0 07:06: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')]} 07:06:32 INFO - https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:06:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:06:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:06:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:06:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:06:34 INFO - Downloaded 10840239 bytes. 07:06:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:06:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:06:34 INFO - Return code: 0 07:06: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')]} 07:06:34 INFO - https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:06:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:06:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:06:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:06:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:06:35 INFO - Downloaded 58362264 bytes. 07:06:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:06:35 INFO - mkdir: /builds/slave/test/properties 07:06:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:06:35 INFO - Writing to file /builds/slave/test/properties/build_url 07:06:35 INFO - Contents: 07:06:35 INFO - build_url:https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:06:36 INFO - Running post-action listener: _resource_record_post_action 07:06:36 INFO - Running post-action listener: set_extra_try_arguments 07:06:36 INFO - ##### 07:06:36 INFO - ##### Finished download-and-extract step (success) 07:06:36 INFO - ##### 07:06:36 INFO - ##### 07:06:36 INFO - ##### Running populate-webroot step. 07:06:36 INFO - ##### 07:06:36 INFO - Running pre-action listener: _resource_record_pre_action 07:06:36 INFO - Running main action method: populate_webroot 07:06:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:06:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:06:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:06:36 INFO - '--tppagecycles', 07:06:36 INFO - '7'], 07:06:36 INFO - 'tests': ['tsvgm']}}, 07:06:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:06:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:06:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:06:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:06:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:06:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:06:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['damp', 'tps']}, 07:06:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'tests': ['damp', 'tps']}, 07:06:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['dromaeo_dom']}, 07:06:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:06:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['a11yr', 07:06:36 INFO - 'ts_paint', 07:06:36 INFO - 'tpaint', 07:06:36 INFO - 'sessionrestore', 07:06:36 INFO - 'sessionrestore_no_auto_restore', 07:06:36 INFO - 'tabpaint']}, 07:06:36 INFO - 'other-e10s': {'tests': ['a11yr', 07:06:36 INFO - 'ts_paint', 07:06:36 INFO - 'tpaint', 07:06:36 INFO - 'sessionrestore', 07:06:36 INFO - 'sessionrestore_no_auto_restore', 07:06:36 INFO - 'tabpaint']}, 07:06:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:06:36 INFO - 'ts_paint', 07:06:36 INFO - 'tpaint', 07:06:36 INFO - 'sessionrestore', 07:06:36 INFO - 'sessionrestore_no_auto_restore', 07:06:36 INFO - 'tabpaint']}, 07:06:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:06:36 INFO - 'ts_paint', 07:06:36 INFO - 'tpaint', 07:06:36 INFO - 'sessionrestore', 07:06:36 INFO - 'sessionrestore_no_auto_restore', 07:06:36 INFO - 'tabpaint']}, 07:06:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['a11yr', 07:06:36 INFO - 'ts_paint', 07:06:36 INFO - 'tpaint', 07:06:36 INFO - 'sessionrestore', 07:06:36 INFO - 'sessionrestore_no_auto_restore', 07:06:36 INFO - 'tabpaint']}, 07:06:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['a11yr', 07:06:36 INFO - 'ts_paint', 07:06:36 INFO - 'tpaint', 07:06:36 INFO - 'sessionrestore', 07:06:36 INFO - 'sessionrestore_no_auto_restore', 07:06:36 INFO - 'tabpaint']}, 07:06:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['tsvgx', 07:06:36 INFO - 'tsvgr_opacity', 07:06:36 INFO - 'tart', 07:06:36 INFO - 'tscrollx', 07:06:36 INFO - 'cart']}, 07:06:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:06:36 INFO - 'tsvgr_opacity', 07:06:36 INFO - 'tart', 07:06:36 INFO - 'tscrollx', 07:06:36 INFO - 'cart']}, 07:06:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'talos_options': ['--disable-e10s'], 07:06:36 INFO - 'tests': ['tp5o']}, 07:06:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'tests': ['tp5o']}, 07:06:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'talos_options': ['--disable-e10s', 07:06:36 INFO - '--xperf_path', 07:06:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:06:36 INFO - 'tests': ['tp5n']}, 07:06:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:36 INFO - 'talos_options': ['--xperf_path', 07:06:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:06:36 INFO - 'tests': ['tp5n']}}, 07:06:36 INFO - 'talos.zip': {'path': '', 07:06:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:06:36 INFO - Running post-action listener: _resource_record_post_action 07:06:36 INFO - ##### 07:06:36 INFO - ##### Finished populate-webroot step (success) 07:06:36 INFO - ##### 07:06:36 INFO - ##### 07:06:36 INFO - ##### Running create-virtualenv step. 07:06:36 INFO - ##### 07:06:36 INFO - Running pre-action listener: _resource_record_pre_action 07:06:36 INFO - Running main action method: create_virtualenv 07:06:36 INFO - Creating virtualenv /builds/slave/test/build/venv 07:06:36 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 07:06:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:06:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:06:36 INFO - Using real prefix '/usr' 07:06:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:06:36 INFO - Installing distribute.............................................................................................................................................................................................done. 07:06:38 INFO - Installing pip.................done. 07:06:38 INFO - Return code: 0 07:06:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:06:38 INFO - Using env: {'DISPLAY': ':0', 07:06:38 INFO - 'HOME': '/home/cltbld', 07:06:38 INFO - 'LANG': 'en_US.UTF-8', 07:06:38 INFO - 'LANGUAGE': 'en_US:en', 07:06:38 INFO - 'LOGNAME': 'cltbld', 07:06:38 INFO - 'MAIL': '/var/mail/cltbld', 07:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:38 INFO - 'MOZ_NO_REMOTE': '1', 07:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:38 INFO - 'NO_EM_RESTART': '1', 07:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:38 INFO - 'PWD': '/builds/slave/test', 07:06:38 INFO - 'SHELL': '/bin/bash', 07:06:38 INFO - 'SHLVL': '1', 07:06:38 INFO - 'TERM': 'linux', 07:06:38 INFO - 'TMOUT': '86400', 07:06:38 INFO - 'USER': 'cltbld', 07:06:38 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:38 INFO - '_': '/tools/buildbot/bin/python'} 07:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:39 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 07:06:39 INFO - Cleaning up... 07:06:39 INFO - Return code: 0 07:06:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:39 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:39 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>=0.7.1'] in /builds/slave/test/build 07:06:39 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>=0.7.1 07:06:39 INFO - Using env: {'DISPLAY': ':0', 07:06:39 INFO - 'HOME': '/home/cltbld', 07:06:39 INFO - 'LANG': 'en_US.UTF-8', 07:06:39 INFO - 'LANGUAGE': 'en_US:en', 07:06:39 INFO - 'LOGNAME': 'cltbld', 07:06:39 INFO - 'MAIL': '/var/mail/cltbld', 07:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:39 INFO - 'MOZ_NO_REMOTE': '1', 07:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:39 INFO - 'NO_EM_RESTART': '1', 07:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:39 INFO - 'PWD': '/builds/slave/test', 07:06:39 INFO - 'SHELL': '/bin/bash', 07:06:39 INFO - 'SHLVL': '1', 07:06:39 INFO - 'TERM': 'linux', 07:06:39 INFO - 'TMOUT': '86400', 07:06:39 INFO - 'USER': 'cltbld', 07:06:39 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:39 INFO - '_': '/tools/buildbot/bin/python'} 07:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:39 INFO - Downloading/unpacking psutil>=0.7.1 07:06: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 07:06: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 07:06: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 07:06: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 07:06: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 07:06: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 07:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:06:41 INFO - Installing collected packages: psutil 07:06:41 INFO - Running setup.py install for psutil 07:06:41 INFO - building 'psutil._psutil_linux' extension 07:06:41 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 07:06:41 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 07:06:41 INFO - building 'psutil._psutil_posix' extension 07:06:41 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 07:06:41 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 07:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:06:41 INFO - Successfully installed psutil 07:06:41 INFO - Cleaning up... 07:06:41 INFO - Return code: 0 07:06:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 07:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 07:06:41 INFO - Using env: {'DISPLAY': ':0', 07:06:41 INFO - 'HOME': '/home/cltbld', 07:06:41 INFO - 'LANG': 'en_US.UTF-8', 07:06:41 INFO - 'LANGUAGE': 'en_US:en', 07:06:41 INFO - 'LOGNAME': 'cltbld', 07:06:41 INFO - 'MAIL': '/var/mail/cltbld', 07:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:41 INFO - 'MOZ_NO_REMOTE': '1', 07:06:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:41 INFO - 'NO_EM_RESTART': '1', 07:06:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:41 INFO - 'PWD': '/builds/slave/test', 07:06:41 INFO - 'SHELL': '/bin/bash', 07:06:41 INFO - 'SHLVL': '1', 07:06:41 INFO - 'TERM': 'linux', 07:06:41 INFO - 'TMOUT': '86400', 07:06:41 INFO - 'USER': 'cltbld', 07:06:41 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:41 INFO - '_': '/tools/buildbot/bin/python'} 07:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:06:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:06:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:06:43 INFO - Installing collected packages: mozsystemmonitor 07:06:43 INFO - Running setup.py install for mozsystemmonitor 07:06:44 INFO - Successfully installed mozsystemmonitor 07:06:44 INFO - Cleaning up... 07:06:44 INFO - Return code: 0 07:06:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:44 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 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:44 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 07:06:44 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 07:06:44 INFO - Using env: {'DISPLAY': ':0', 07:06:44 INFO - 'HOME': '/home/cltbld', 07:06:44 INFO - 'LANG': 'en_US.UTF-8', 07:06:44 INFO - 'LANGUAGE': 'en_US:en', 07:06:44 INFO - 'LOGNAME': 'cltbld', 07:06:44 INFO - 'MAIL': '/var/mail/cltbld', 07:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:44 INFO - 'MOZ_NO_REMOTE': '1', 07:06:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:44 INFO - 'NO_EM_RESTART': '1', 07:06:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:44 INFO - 'PWD': '/builds/slave/test', 07:06:44 INFO - 'SHELL': '/bin/bash', 07:06:44 INFO - 'SHLVL': '1', 07:06:44 INFO - 'TERM': 'linux', 07:06:44 INFO - 'TMOUT': '86400', 07:06:44 INFO - 'USER': 'cltbld', 07:06:44 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:44 INFO - '_': '/tools/buildbot/bin/python'} 07:06:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:44 INFO - Downloading/unpacking blobuploader==1.2.4 07:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:46 INFO - Downloading blobuploader-1.2.4.tar.gz 07:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:06:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:06:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:46 INFO - Downloading docopt-0.6.1.tar.gz 07:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:06:46 INFO - Installing collected packages: blobuploader, requests, docopt 07:06:46 INFO - Running setup.py install for blobuploader 07:06:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:06:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:06:47 INFO - Running setup.py install for requests 07:06:47 INFO - Running setup.py install for docopt 07:06:47 INFO - Successfully installed blobuploader requests docopt 07:06:47 INFO - Cleaning up... 07:06:47 INFO - Return code: 0 07:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:06:47 INFO - Using env: {'DISPLAY': ':0', 07:06:47 INFO - 'HOME': '/home/cltbld', 07:06:47 INFO - 'LANG': 'en_US.UTF-8', 07:06:47 INFO - 'LANGUAGE': 'en_US:en', 07:06:47 INFO - 'LOGNAME': 'cltbld', 07:06:47 INFO - 'MAIL': '/var/mail/cltbld', 07:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:47 INFO - 'MOZ_NO_REMOTE': '1', 07:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:47 INFO - 'NO_EM_RESTART': '1', 07:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:47 INFO - 'PWD': '/builds/slave/test', 07:06:47 INFO - 'SHELL': '/bin/bash', 07:06:47 INFO - 'SHLVL': '1', 07:06:47 INFO - 'TERM': 'linux', 07:06:47 INFO - 'TMOUT': '86400', 07:06:47 INFO - 'USER': 'cltbld', 07:06:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:47 INFO - '_': '/tools/buildbot/bin/python'} 07:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:06:47 INFO - Running setup.py (path:/tmp/pip-HeoNNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:06:47 INFO - Running setup.py (path:/tmp/pip-yWDNq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:06:47 INFO - Running setup.py (path:/tmp/pip-K_R7Rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:06:47 INFO - Running setup.py (path:/tmp/pip-n8AB45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:06:48 INFO - Running setup.py (path:/tmp/pip-jfzO4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:06:48 INFO - Running setup.py (path:/tmp/pip-OgxVCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:06:48 INFO - Running setup.py (path:/tmp/pip-FIGHun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:06:48 INFO - Running setup.py (path:/tmp/pip-3O6aC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:06:48 INFO - Running setup.py (path:/tmp/pip-ipv6c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:06:48 INFO - Running setup.py (path:/tmp/pip-0E3ufY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:06:48 INFO - Running setup.py (path:/tmp/pip-48q1_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:06:48 INFO - Running setup.py (path:/tmp/pip-xDKQwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:06:48 INFO - Running setup.py (path:/tmp/pip-CtrH8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:06:48 INFO - Running setup.py (path:/tmp/pip-muf0zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:48 INFO - Running setup.py (path:/tmp/pip-XZC88M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:06:48 INFO - Running setup.py (path:/tmp/pip-rAvJU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:06:48 INFO - Running setup.py (path:/tmp/pip-ghFsNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:06:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:06:48 INFO - Running setup.py install for manifestparser 07:06:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Running setup.py install for mozcrash 07:06:49 INFO - Running setup.py install for mozdebug 07:06:49 INFO - Running setup.py install for mozdevice 07:06:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Running setup.py install for mozfile 07:06:49 INFO - Running setup.py install for mozhttpd 07:06:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Running setup.py install for mozinfo 07:06:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Running setup.py install for mozInstall 07:06:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Running setup.py install for mozleak 07:06:49 INFO - Running setup.py install for mozlog 07:06:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:06:49 INFO - Running setup.py install for moznetwork 07:06:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:06:50 INFO - Running setup.py install for mozprocess 07:06:50 INFO - Running setup.py install for mozprofile 07:06:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:06:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:06:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:06:50 INFO - Running setup.py install for mozrunner 07:06:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:06:50 INFO - Running setup.py install for mozscreenshot 07:06:50 INFO - Running setup.py install for moztest 07:06:50 INFO - Running setup.py install for mozversion 07:06:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:06:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:06:50 INFO - Cleaning up... 07:06:50 INFO - Return code: 0 07:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:06:50 INFO - Using env: {'DISPLAY': ':0', 07:06:50 INFO - 'HOME': '/home/cltbld', 07:06:50 INFO - 'LANG': 'en_US.UTF-8', 07:06:50 INFO - 'LANGUAGE': 'en_US:en', 07:06:50 INFO - 'LOGNAME': 'cltbld', 07:06:50 INFO - 'MAIL': '/var/mail/cltbld', 07:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:50 INFO - 'MOZ_NO_REMOTE': '1', 07:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:50 INFO - 'NO_EM_RESTART': '1', 07:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:50 INFO - 'PWD': '/builds/slave/test', 07:06:50 INFO - 'SHELL': '/bin/bash', 07:06:50 INFO - 'SHLVL': '1', 07:06:50 INFO - 'TERM': 'linux', 07:06:50 INFO - 'TMOUT': '86400', 07:06:50 INFO - 'USER': 'cltbld', 07:06:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:50 INFO - '_': '/tools/buildbot/bin/python'} 07:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:06:50 INFO - Running setup.py (path:/tmp/pip-AeGVdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:06:51 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)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:06:51 INFO - Running setup.py (path:/tmp/pip-qwOrN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:06:51 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)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:06:51 INFO - Running setup.py (path:/tmp/pip-1HwuoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:06:51 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)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:06:51 INFO - Running setup.py (path:/tmp/pip-8asTRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:06:51 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)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:06:51 INFO - Running setup.py (path:/tmp/pip-bkO0l5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:06:51 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)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:06:51 INFO - Running setup.py (path:/tmp/pip-4rgQww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:06:51 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)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:06:51 INFO - Running setup.py (path:/tmp/pip-63DdFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:06:51 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)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:06:51 INFO - Running setup.py (path:/tmp/pip-gsayoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:06:51 INFO - Running setup.py (path:/tmp/pip-IptG_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:06:51 INFO - Running setup.py (path:/tmp/pip-EoJyKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:06:51 INFO - Running setup.py (path:/tmp/pip-JO_J6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:06:51 INFO - Running setup.py (path:/tmp/pip-7ZXKQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:06:51 INFO - Running setup.py (path:/tmp/pip-lvhQno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:06:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:06:51 INFO - Running setup.py (path:/tmp/pip-3HkQrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:06:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:52 INFO - Running setup.py (path:/tmp/pip-by40DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:06:52 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)) 07:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:06:52 INFO - Running setup.py (path:/tmp/pip-CRQkRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:06:52 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)) 07:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:06:52 INFO - Running setup.py (path:/tmp/pip-WlOxt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:06:52 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)) 07:06:52 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)) 07:06:52 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)) 07:06:52 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)) 07:06:52 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)) 07:06:52 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)) 07:06:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:06:52 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 07:06:52 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 07:06:52 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 07:06:52 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 07:06:52 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 07:06:52 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 07:06:54 INFO - Downloading blessings-1.6.tar.gz 07:06:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:06:54 INFO - Installing collected packages: blessings 07:06:54 INFO - Running setup.py install for blessings 07:06:54 INFO - Successfully installed blessings 07:06:54 INFO - Cleaning up... 07:06:54 INFO - Return code: 0 07:06:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:06:54 INFO - Reading from file tmpfile_stdout 07:06:54 INFO - Current package versions: 07:06:54 INFO - argparse == 1.2.1 07:06:54 INFO - blessings == 1.6 07:06:54 INFO - blobuploader == 1.2.4 07:06:54 INFO - docopt == 0.6.1 07:06:54 INFO - manifestparser == 1.1 07:06:54 INFO - mozInstall == 1.12 07:06:54 INFO - mozcrash == 0.17 07:06:54 INFO - mozdebug == 0.1 07:06:54 INFO - mozdevice == 0.48 07:06:54 INFO - mozfile == 1.2 07:06:54 INFO - mozhttpd == 0.7 07:06:54 INFO - mozinfo == 0.9 07:06:54 INFO - mozleak == 0.1 07:06:54 INFO - mozlog == 3.2 07:06:54 INFO - moznetwork == 0.27 07:06:54 INFO - mozprocess == 0.22 07:06:54 INFO - mozprofile == 0.28 07:06:54 INFO - mozrunner == 6.11 07:06:54 INFO - mozscreenshot == 0.1 07:06:54 INFO - mozsystemmonitor == 0.0 07:06:54 INFO - moztest == 0.7 07:06:54 INFO - mozversion == 1.4 07:06:54 INFO - psutil == 3.1.1 07:06:54 INFO - requests == 1.2.3 07:06:54 INFO - wsgiref == 0.1.2 07:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:06:54 INFO - Using env: {'DISPLAY': ':0', 07:06:54 INFO - 'HOME': '/home/cltbld', 07:06:54 INFO - 'LANG': 'en_US.UTF-8', 07:06:54 INFO - 'LANGUAGE': 'en_US:en', 07:06:54 INFO - 'LOGNAME': 'cltbld', 07:06:54 INFO - 'MAIL': '/var/mail/cltbld', 07:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:54 INFO - 'MOZ_NO_REMOTE': '1', 07:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:54 INFO - 'NO_EM_RESTART': '1', 07:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:54 INFO - 'PWD': '/builds/slave/test', 07:06:54 INFO - 'SHELL': '/bin/bash', 07:06:54 INFO - 'SHLVL': '1', 07:06:54 INFO - 'TERM': 'linux', 07:06:54 INFO - 'TMOUT': '86400', 07:06:54 INFO - 'USER': 'cltbld', 07:06:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:54 INFO - '_': '/tools/buildbot/bin/python'} 07:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:06:54 INFO - Cleaning up... 07:06:54 INFO - Return code: 0 07:06:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:06:54 INFO - Using env: {'DISPLAY': ':0', 07:06:54 INFO - 'HOME': '/home/cltbld', 07:06:54 INFO - 'LANG': 'en_US.UTF-8', 07:06:54 INFO - 'LANGUAGE': 'en_US:en', 07:06:54 INFO - 'LOGNAME': 'cltbld', 07:06:54 INFO - 'MAIL': '/var/mail/cltbld', 07:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:54 INFO - 'MOZ_NO_REMOTE': '1', 07:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:54 INFO - 'NO_EM_RESTART': '1', 07:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:54 INFO - 'PWD': '/builds/slave/test', 07:06:54 INFO - 'SHELL': '/bin/bash', 07:06:54 INFO - 'SHLVL': '1', 07:06:54 INFO - 'TERM': 'linux', 07:06:54 INFO - 'TMOUT': '86400', 07:06:54 INFO - 'USER': 'cltbld', 07:06:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:54 INFO - '_': '/tools/buildbot/bin/python'} 07:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:54 INFO - Downloading/unpacking jsonschema 07:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:06:56 INFO - Downloading/unpacking functools32 (from jsonschema) 07:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:57 INFO - Downloading functools32-3.2.3-2.tar.gz 07:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:06:57 INFO - warning: no files found matching '*.txt' 07:06:57 INFO - no previously-included directories found matching 'build' 07:06:57 INFO - no previously-included directories found matching 'dist' 07:06:57 INFO - no previously-included directories found matching '.git*' 07:06:57 INFO - Installing collected packages: jsonschema, functools32 07:06:57 INFO - Running setup.py install for functools32 07:06:57 INFO - warning: no files found matching '*.txt' 07:06:57 INFO - no previously-included directories found matching 'build' 07:06:57 INFO - no previously-included directories found matching 'dist' 07:06:57 INFO - no previously-included directories found matching '.git*' 07:06:57 INFO - Successfully installed jsonschema functools32 07:06:57 INFO - Cleaning up... 07:06:57 INFO - Return code: 0 07:06:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:06:57 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')]} 07:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:57 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')]} 07:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7eff9c17ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, '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': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', '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 07:06:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 07:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 07:06:57 INFO - Using env: {'DISPLAY': ':0', 07:06:57 INFO - 'HOME': '/home/cltbld', 07:06:57 INFO - 'LANG': 'en_US.UTF-8', 07:06:57 INFO - 'LANGUAGE': 'en_US:en', 07:06:57 INFO - 'LOGNAME': 'cltbld', 07:06:57 INFO - 'MAIL': '/var/mail/cltbld', 07:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:57 INFO - 'MOZ_NO_REMOTE': '1', 07:06:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:06:57 INFO - 'NO_EM_RESTART': '1', 07:06:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:57 INFO - 'PWD': '/builds/slave/test', 07:06:57 INFO - 'SHELL': '/bin/bash', 07:06:57 INFO - 'SHLVL': '1', 07:06:57 INFO - 'TERM': 'linux', 07:06:57 INFO - 'TMOUT': '86400', 07:06:57 INFO - 'USER': 'cltbld', 07:06:57 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:57 INFO - '_': '/tools/buildbot/bin/python'} 07:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:57 INFO - Downloading/unpacking flake8 07:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:06:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:06:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:06:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:06:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:06:59 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 07:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:07:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:07:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:07:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:07:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:07:00 INFO - no previously-included directories found matching 'docs/_build' 07:07:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:07:00 INFO - Running setup.py install for pep8 07:07:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:07:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:07:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:07:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:07:00 INFO - no previously-included directories found matching 'docs/_build' 07:07:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:07:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:07:00 INFO - Cleaning up... 07:07:00 INFO - Return code: 0 07:07:00 INFO - Running post-action listener: _resource_record_post_action 07:07:00 INFO - Running post-action listener: _start_resource_monitoring 07:07:00 INFO - Starting resource monitoring. 07:07:00 INFO - ##### 07:07:00 INFO - ##### Finished create-virtualenv step (success) 07:07:00 INFO - ##### 07:07:00 INFO - ##### 07:07:00 INFO - ##### Running install step. 07:07:00 INFO - ##### 07:07:00 INFO - Running pre-action listener: _resource_record_pre_action 07:07:00 INFO - Running main action method: install 07:07:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:07:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:07:00 INFO - Reading from file tmpfile_stdout 07:07:00 INFO - Detecting whether we're running mozinstall >=1.0... 07:07:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:07:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:07:00 INFO - Reading from file tmpfile_stdout 07:07:00 INFO - Output received: 07:07:00 INFO - Usage: mozinstall [options] installer 07:07:00 INFO - Options: 07:07:00 INFO - -h, --help show this help message and exit 07:07:00 INFO - -d DEST, --destination=DEST 07:07:00 INFO - Directory to install application into. [default: 07:07:00 INFO - "/builds/slave/test"] 07:07:00 INFO - --app=APP Application being installed. [default: firefox] 07:07:00 INFO - mkdir: /builds/slave/test/build/application 07:07:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:07:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:07:17 INFO - Reading from file tmpfile_stdout 07:07:17 INFO - Output received: 07:07:17 INFO - /builds/slave/test/build/application/firefox/firefox 07:07:17 INFO - Running post-action listener: _resource_record_post_action 07:07:17 INFO - ##### 07:07:17 INFO - ##### Finished install step (success) 07:07:17 INFO - ##### 07:07:17 INFO - ##### 07:07:17 INFO - ##### Running run-tests step. 07:07:17 INFO - ##### 07:07:17 INFO - Running pre-action listener: _resource_record_pre_action 07:07:17 INFO - Running main action method: run_tests 07:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:07:17 INFO - Python 2.7.3 07:07:17 INFO - Return code: 0 07:07:17 INFO - grabbing minidump binary from tooltool 07:07: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')]} 07:07:17 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 0x1764b20>, '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 0x1765a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1765eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:07:17 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 07:07:17 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 07:07:17 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 07:07:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:07:17 INFO - Return code: 0 07:07:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:07:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:07:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:07:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:07:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:07:18 INFO - Return code: 0 07:07:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 07:07:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 07:07:18 INFO - Using env: {'DISPLAY': ':0', 07:07:18 INFO - 'HOME': '/home/cltbld', 07:07:18 INFO - 'LANG': 'en_US.UTF-8', 07:07:18 INFO - 'LANGUAGE': 'en_US:en', 07:07:18 INFO - 'LOGNAME': 'cltbld', 07:07:18 INFO - 'MAIL': '/var/mail/cltbld', 07:07:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:07:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:07:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:18 INFO - 'MOZ_NO_REMOTE': '1', 07:07:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:07:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:18 INFO - 'NO_EM_RESTART': '1', 07:07:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:18 INFO - 'PWD': '/builds/slave/test', 07:07:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:07:18 INFO - 'SHELL': '/bin/bash', 07:07:18 INFO - 'SHLVL': '1', 07:07:18 INFO - 'TERM': 'linux', 07:07:18 INFO - 'TMOUT': '86400', 07:07:18 INFO - 'USER': 'cltbld', 07:07:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866', 07:07:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:18 INFO - '_': '/tools/buildbot/bin/python'} 07:07:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 07:07:18 INFO - mozversion application_buildid: 20160512043053 07:07:18 INFO - mozversion application_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 07:07:18 INFO - mozversion application_display_name: Nightly 07:07:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:07:18 INFO - mozversion application_name: Firefox 07:07:18 INFO - mozversion application_remotingname: firefox 07:07:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:07:18 INFO - mozversion application_vendor: Mozilla 07:07:18 INFO - mozversion application_version: 49.0a1 07:07:18 INFO - mozversion platform_buildid: 20160512043053 07:07:18 INFO - mozversion platform_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 07:07:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:07:18 INFO - mozversion platform_version: 49.0a1 07:07:18 INFO - using testdate: 1463062038 07:07:18 INFO - actual date: 1463062038 07:07:18 INFO - starting webserver on 'localhost:56663' 07:07:18 INFO - SUITE-START | Running 2 tests 07:07:18 INFO - TEST-START | dromaeo_css 07:07:18 INFO - operating with platform_type : linux_ 07:07:18 INFO - Initialising browser for dromaeo_css test... 07:07:18 INFO - TEST-INFO | started process 12732 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv5md08/profile http://localhost:56663/getInfo.html) 07:07:22 INFO - PROCESS | 12732 | __metrics Screen width/height:1600/1200 07:07:22 INFO - PROCESS | 12732 | colorDepth:24 07:07:22 INFO - PROCESS | 12732 | Browser inner width/height: 1024/697 07:07:22 INFO - PROCESS | 12732 | __metrics 07:07:23 ERROR - PROCESS | 12732 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:07:24 INFO - TEST-INFO | 12732: exit 0 07:07:24 INFO - Browser initialized. 07:07:24 INFO - Running cycle 1/1 for dromaeo_css test... 07:07:24 INFO - TEST-INFO | started process 12813 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv5md08/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:07:35 INFO - PROCESS | 12813 | RSS: Main: 152424448 07:07:35 INFO - PROCESS | 12813 | 07:08:27 INFO - PROCESS | 12813 | 1463062107156 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] 07:10:34 INFO - PROCESS | 12813 | Cycle 1(1): loaded http://localhost:56663/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56663/tests/dromaeo/cssquery-ext.html) 07:10:36 INFO - PROCESS | 12813 | RSS: Main: 285577216 07:10:36 INFO - PROCESS | 12813 | 07:13:38 INFO - PROCESS | 12813 | Cycle 1(1): loaded http://localhost:56663/tests/dromaeo/cssquery-ext.html (next: http://localhost:56663/tests/dromaeo/cssquery-jquery.html) 07:13:39 INFO - PROCESS | 12813 | RSS: Main: 239939584 07:13:39 INFO - PROCESS | 12813 | 07:16:41 INFO - PROCESS | 12813 | Cycle 1(1): loaded http://localhost:56663/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56663/tests/dromaeo/cssquery-mootools.html) 07:16:41 INFO - PROCESS | 12813 | RSS: Main: 233553920 07:16:41 INFO - PROCESS | 12813 | 07:19:43 INFO - PROCESS | 12813 | Cycle 1(1): loaded http://localhost:56663/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56663/tests/dromaeo/cssquery-prototype.html) 07:19:44 INFO - PROCESS | 12813 | RSS: Main: 226672640 07:19:44 INFO - PROCESS | 12813 | 07:22:47 INFO - PROCESS | 12813 | Cycle 1(1): loaded http://localhost:56663/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56663/tests/dromaeo/cssquery-yui.html) 07:22:47 INFO - PROCESS | 12813 | RSS: Main: 204288000 07:22:47 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | Cycle 1(1): loaded http://localhost:56663/tests/dromaeo/cssquery-yui.html (next: http://localhost:56663/tests/dromaeo/cssquery-dojo.html) 07:25:50 INFO - PROCESS | 12813 | RSS: Main: 269205504 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | __start_tp_report 07:25:50 INFO - PROCESS | 12813 | _x_x_mozilla_page_load 07:25:50 INFO - PROCESS | 12813 | _x_x_mozilla_page_load_details 07:25:50 INFO - PROCESS | 12813 | |i|pagename|runs| 07:25:50 INFO - PROCESS | 12813 | |0;dojo.html;3187.7609179311544;3197.4724170511868;3226.967730322696;3254.7558933079995;3276.83615819209;5282.841514754554;5393.946060539393;5433.945660543393;5547.7780888764455;5568.693721845297;5476;5514.338279406476;5521.806736764214;5549.084401073828;5559.749811258493;4870.683405578626;4911.263310503436;5048.7223202723835;5080.212567052113;5085.389753229618;4837.588804951583;5023.120953833065;5024.748762561876;5048;5106.310648062501;4869.756512174395;4897.240927656219;4912.7297998610065;4933.40799104108;4976.2286845539;4896.828610998634;4911.042346742387;4983.72589507577;4984.177610694231;5043.041822053798;4788.449328327218;4923.704577725348;4962.87592810179;4969.329140566049;5030.522100400456;5244.973775131138;5361.276227709248;5400.081986062379;5420.83737487876;5462.071447853835;5849.824505264849;5865.442782935615;5904.498117660004;5910.231669882931;5960.7019649017375;5957.523398128183;5971.731272092767;5971.850703732398;6001.039833626599;6026.939730602681;5269.209618557209;5435.891282174374;5456.345238571357;5482.314710661168;5579.721013949286;6024.487918526975;6077.9392206078255;6078.908816367758;6081.17904082952;6084.056971169476;5591.07747221712;5795.710214489259;5796;5828.854278642983;5847.1229315603;5816.738246778905;5817.447342502455;5848.619839710405;5872.383399742966;5897.616654917417;5773.191753154561;5845.064789633639;5854.151148083504;5858.794942177004;5862;5600.215969764236;5637.577181711388;5835.35811060783;5862.062070068769;5889.057750759858;4371.147626212922;4378.255696531597;4394.890127746768;4395.077033822933;4430.867073987721;3912.374020156761;3914.8042597869994;3916.5887581804072;3954.7231693781737;3965.5836137205756;5313.043652142652;5471.7264136793;5504.6421982571;5508.641938274;5512.751926163332;5800.6809625471005;5833.0375488044;5837.29952405714;5940.138679891392;5968.910466343008;5513.2005859150195;5521.503064724114;5523.171524271311;5537.8061767837935;5544.140658197985;4894.19245824435;4897.289892965571;4902.534259245366;4904.4605093439695;4956.4547899731;5819.098039803837;6030.216071910623;6063.363346848518;6083.604565703304;6125.877482450326;5961.970190149022;5965.015772397506;5965.045592705146;5966.343702192842;5968.582199246185;5802.1586869902785;6073.817785466443;6118.082287656887;6137.662428566472;6140.815775526741;5720.48515633595;5733.082706766897;5752.022156233366;5758.366579676316;5806.506446952057;5868.765249390148;5873;5885.911711324419;5887.646741195542;5890.4993075589055;5480.588955828409;5636.633618814764;5718.199452076629;5730.22641943349;5742.827715168552;5662;5744.253247078021;5745.368009519033;5753.252077229933;5799.449052340021;5650.943490565206;5663.915041274302;5664.433556644302;5669.234653321749;5670.631408958405;5545.694986775766;5667.801626943037;5698.287714035746;5779.566532509994;5791.102379131242;5477.178423236546;5528.06022976087;5552.583556233218;5568.470995255444;5715.657060576379 07:25:50 INFO - PROCESS | 12813 | |1;ext.html;603.2067830802495;643.9792928208788;647.9319671434499;648.8248172993295;661.6526323680071;30460.54309185364;31016;43214.35178472318;43364.78317608415;44551.77724111383;42339.78830105853;42850.571494285046;42853.7857310713;44720.552794472045;44737.10525789482;8813.647454101829;8972.730818075435;8978.50618215998;8989.685361012365;8989.730308090735;7951.165127661599;10414.375137491776;10685.572577096946;10692.144628429707;10692.51883665233;10369;10495.895041049605;10496.107830834388;10760.515776790064;10770.353778773298;10598.947005264963;10616.681499555025;10624.309419887682;10676.893231067668;10702.892971070307;10416.114630256438;10491.475426228659;10515.211359148094;10517.368957862553;10525.10536604382;44089;44392.3341149883;44441.55558444407;44475.55524444747;44476;3553.626869178725;3598.6401359864067;3739.046543131511;3988.980055099706;4265.7014009019385;33337.16657083568;33385;34254;34307.14232144191;34325.313493729984;4349.978250108761;4491.517799126281;4504.48198457175;4509.684322097454;4516.864494065183;331858;333092;333504.3324783385;335223;335481;65845.67077164583;66264.6686766563;66364.6681766588;66460.33539664638;66467;69541.65229173822;69594.3040569598;69627.3037269631;69633.3036669637;69645.30354696489;17620.82379176192;18096.371326580673;18122.365717199835;18137.36519221821;18146.45560633183;13718;13797.793033104512;13807.585772426843;13812.86187138136;13814.585562433143;19431.902840485705;20464.897675511525;20465.795342046687;20533.17867285292;20556.17775288972;7444.367228785614;7477.1775104738435;7574.166841647414;7780.299773020455;7844.176361482077;998.1665309466531;1090.3512410115968;1107.2028139739375;1109.4563663804838;1127.1658972360394;32407.189820254684;32541.51187732186;32554.674453255637;32839;32840.835795820865;1107.374333501569;1139.4132022008673;1165.2425923149851;1170.9590164344377;1176.5940750441255;16792.908460950242;17149.91425042867;17253.741193882102;17411.825881741275;17630.647387052442;476605;482782;483192;483699;484017;58281.4171858276;59227;59500.40499595035;59567.702161488916;59649.70175149097;1316.8683131686946;1396.2111407055183;1420.31824724133;1421.7938398932097;1425.600831767107;32791.67208327886;33726.325473490884;33927.66072339344;34078.318433631684;34748.30503389968;50184.24723629221;57121;57912;58293;63459;437034;438829;440134;442740;443830;26197.869010654824;29023.27441813887;29055;29107.85446072756;29532.557011644414;48935.5106448931;49161.50838491712;49339;49622.25566616576;49651.75174124106;40781.184376311714;40912.79543602263;42073;42099.368509472995;42991.57008429876;40134;40158.79920600378;40673.79663101665;40795;41127.794361028005;40796.79601601973;40907.1818563633;41381;41427.58572414358;41601.79199103985;35138;35812.104697382776;36498.45252321164;36678.63321366859;36717.89846304615;28039.579406308512;28045.719542804312;28060.438791223653;28358.291042724097;28440.431191375643 07:25:50 INFO - PROCESS | 12813 | |2;jquery.html;412.25382058474185;412.3381971935046;413.23963906412206;420.15968063872253;427.77969345786903;13587.456501739944;17843.28626854926;18817.717734233964;19570.21719131235;19587.314443994463;14500.854991450082;16786.66426671466;17054.82945170548;17501.82498175018;18223.635527289447;5685.1756495308255;5802.738876750535;5815.302163740357;5824.767009319622;5877.412258774131;5762.250907382056;5791.681457519835;5830.475257226849;5837.8832423351505;5845.912311315334;4171.20747058058;6091.208142941434;6383.138276332682;6416.3262857400005;6506.642134682591;6412.070249813797;6427.871442571146;6448.290688024314;6483.448906842924;6511.674416279167;5593.608447408683;5718.714064296769;5737.827865164052;5747.540196784247;5762.250907382014;21777.455563610878;21868;22082.88958555213;22268;22458.101675932943;2258.6160352739907;2364.6285154610287;2433.9878300608384;2451.534208500397;2546.7580579844885;13202.933985330108;14076.507322243775;14237.430502779876;14498.202598857059;14566.344514496874;1925.749652545174;1974.1412485568824;1978.8317992970472;2007.7691065527506;2011.1452632631074;78905;81533.59233203855;84042;85037.57481212555;85155.14844851533;10572.304062464176;10576.153907687367;10598.25812193139;10815.35107893529;10995.670129896116;15243.771343429857;15259;15282;15301.311440985182;15306.311215995307;7826.099998500245;7890.566018869017;7965.561894095765;8055.817951908964;8122.350211983027;7440.516366436164;7444.516106453063;7476.887846682304;7564.394848412114;7681.462297639224;5731.254936858182;6187;6611.082785992105;6622.337766223339;6660.800175994728;1842.677531431994;1892.8674992750375;1916.1377380178976;1948.2888745608034;1955.599102184049;977.8191034658469;1066.3548553125402;1073.6671631794168;1115.70991542198;1203.7111093337535;18184;18612.44162675122;19095.427137185907;19134.234630614897;19292.32476863303;1043.5877828257946;1076.4994277660926;1097.138746084336;1097.5390336058874;1181.5746331320718;10342.37945723259;11089.778204435866;11171.497282622302;11176.944115279533;11185.66443006711;488764;494332;494769;501160;501323;84962.57518712491;85319.57340213259;87042.56478717693;102320.48839755902;103602;1061.9575216991389;1074.7259448840653;1100.834874768791;1104.7624760676492;1107.4850194659523;16577.064051364585;16586.25361858695;16868.915655421646;17250.827491724922;17378.47864564065;40844.1831163381;41465;41770.791146044074;42155.789221053696;43594.56405435905;59572.70213648904;60078;60490;60581.69709151426;61905.285682859176;8295.253427191581;8643.524606146722;8746.68799680053;8842.867356989778;8873.378863479495;8996;11155.665330040112;12858.807117893051;13522.391492382669;13771.931140344635;11096.167787415814;11554.191206615456;11594.47824847867;11677.88322116768;11701;10148.79702405933;10435.68692939213;11580.884191158317;11650.708732281422;11916.344601047025;11162.720931976766;11342.149338799458;11466.885331146916;11599.76800464003;12081.154319197572;8707.346948978979;8777.210051095431;8875.334349923653;8885.911140888767;8914.643414263357;10229.232807539316;11334.546618135511;11545.942270288931;11820.172587918763;11897.643070707893 07:25:50 INFO - PROCESS | 12813 | |3;mootools.html;1370.6573356660836;1529.3653133949415;1578.1556867076115;1766.9381571644994;1908.8377487913526;7885.0932142803595;8069.4754840935375;8091.393145514088;8246.134155913633;8262.049864265611;8057.879131813028;8197.877031844511;8219.095899451071;8235.423520353577;8243.752687419386;2058.4853786553363;2310.4339436837954;2325.2791634593245;2340.0756701103055;2381.368937231635;2448.4123810285582;2475.7029156501244;2476.19523654812;2477.02157647729;2477.4301910560494;2374.1809075868796;2413.095089341497;2414.070582825616;2417.9637305440433;2418.818588605862;1965.931192408266;2214.247155966979;2270.579942710596;2277.874716890587;2282.155602427096;2323.0707716913203;2331.8717470539113;2339.8245131615026;2341.988290058539;2345.0502546468706;8894.866577001285;8967.955160224223;8968.865467018;8981.371304008722;8986.595603197871;5410.323709536308;5512.3385193777;5558.082916318803;5558.610897237195;5572.637778544382;3321.086701157177;3406.812625305607;3420.1620602952435;3524.8237588120746;3576.302620988908;1186.6321440353518;1205.6624145239348;1219.1465973818363;1247.8876901078856;1261.735035642516;12442.253464792142;12547.93726031373;12579.245245285312;12580.30808305552;12591.685207869785;4654.604358629555;4720.409948756405;4809.927851082237;4846.466888642247;4849.466558678542;9792.363496372713;9807.166390856715;9816.214702823758;9844.9015509844;9845.261605379625;5376.086065368897;5419.214213939035;5462.562994960394;5484.91772623411;5485.177223416519;4346.565343465628;4993.875153121201;5095.414027386824;5110.66780659256;5148.459411761786;3919.862804801818;3967.940480892789;4004.3993400990084;4047.1096358801015;4068.898277543085;1186.8252424763723;1196.970075748096;1197.21582363552;1200.2138599217526;1212.345333519907;1030.4538594544904;1034.4879284754095;1037.7457522906784;1043.12377602814;1052.6105341023717;6129.570930034854;6265.686715664198;6333.113364129025;6364.522660800459;6485.416312531895;463.0159765405222;463.15243105118225;487.373724763677;489.9857295398562;499.40570720842084;4281.464816897887;4356.934645980313;4372.23485889968;4386.956130438654;4423.8230470781455;10973.122150227962;11160.218784685156;11406.885931140749;11406.942965285121;11443.084553235722;11125.276857004268;11167.162462815322;11267.436628168558;11273.492692828842;11280.32318060919;8014.998125234346;8139.348852091818;8245.79385515367;8423.073461919306;8489.235968762841;6203.348648391854;6344.111824344688;6393.680315984275;6465.0948867157695;6545.181852268466;10063.547140378554;10117.949410252902;10242.897571024194;10406.947965260126;10445.738856528647;10416.166706663296;10488.160947124059;10489.790204196026;10506.789864202827;10623.415712135908;7245.239249878687;7728.806779830549;7729.9227007729205;7933.60331983387;8087.716929907425;8806.559672016245;8807.339449541181;8838.558072096497;8858.689945851864;8873.645054198018;7723;7883.724069657764;8066.7579972600915;8130.105688374392;8229.547374894439;7576.1287451941525;7584;7609.6575654096805;7762.728304509315;7922.247386498273;6879.449644028566;6950.965245173742;7062.752803651848;7077.5399599025895;7088.397486213524;5516.365617953987;5524.309461317335;5605.131204663284;5617.101263797773;5702.942970570241;5361.035013697572;5437.809676661298;5519.613627046068;5570.997220500187;5648.689322087161 07:25:50 INFO - PROCESS | 12813 | |4;prototype.html;331.20180365319584;342.09004049229054;343.66321005414693;344.7242206235012;344.8430515620093;5319.893602127955;5349.117395629726;5374.462553744628;5448.972755136224;5483.122700367942;5293.788248470057;5328.813491527797;5332.253484512162;5352.625316227865;5371.167469042305;4170.374443833419;4231.322988321869;4242.851500197494;4250.638695710868;4283.828646854137;4188.706790524665;4270.252705776492;4274.807633656477;4277.8288868445225;4299.118680670471;4238.851640192594;4239.894002649943;4247.895547157746;4248.940762650886;4290.742555446684;3951.7826519541413;4102.938455923164;4187.664986801049;4264.381664658638;4278.572142785728;3763.397856342972;3789.2800367930004;3795.6773674237725;3798.810059497014;3803.9809800951093;5016.347874776256;5191.480851914816;5246.137621144477;5255.395629502618;5266.525372895595;4680.882977925545;4897.828575999859;4909;4926.531979461981;4936.580390666762;5644.238027866268;5699.601027928046;5705.657660540381;5706.54347652191;5726.284214473191;4000.939750965967;4202.2646036943415;4232.534421213695;4248.957510424887;4258.978705106455;5842.737076831553;5859.3554708981555;5911.083782013795;5934.643921364732;5952.970235148797;4313.956860431418;4318.978405107954;4341.39220509129;4346.413559050924;4349.933781453784;4158.4178215049915;4394.2749446340995;4401.273789824707;4410.33844923264;4415.977920110379;4904.828331008398;4925.458199598042;4945.554900059012;4954.504549545089;4963.528464795839;5695.5443564514735;5722.656640601577;5723.624695751299;5741.913871291934;5755.913661295084;1479.5344345211495;1519.187234829361;1532.8007359043254;1533.74693175627;1534.1792141204403;979.4221409368507;990.9752256193652;995.9442990430167;1008.9798204035878;1012.9993263304914;4840.443349014909;4897.583705385011;4907.3620429343955;4907.55831975124;4978.47725988766;5174.008006917364;5188.495336352491;5207.895842083137;5224.164133738584;5228.163493840967;4905.87735306613;4921.803127874917;5065.797368105308;5230.764615592308;5233.712145832016;3104.348086901776;3124.7657175415584;3133.310671652233;3134.8902788402297;3137.8431078445988;6013.4888534474185;6021.548383871227;6022.307434644984;6050.42520960508;6059.424354686386;5727.427257274239;5912.231409916683;5924.822255332347;5967.641941483438;5973.1936188614845;5941.881162376728;5969.910451343233;5981.222441082718;6004.699765011819;6023.819285421444;5649.20911072442;5705.37396841898;5817.301923769175;5860.472557469848;5865.736041878211;5699.971500142473;5718.599698021098;5731.627444216113;5749.8275051748515;5752.223449834219;5986.341502434641;6013.729382177725;6021.668808215415;6028.457438830526;6041.063635136561;4103.692223083221;4283.443152390169;4297.806598703129;4306.590873866978;4321.351797230441;5768.711564421678;5781.046127388934;5794.652320860761;5815.621984570989;5850.970745146247;5579.804706835242;5702.971485142548;5709.486146246858;5728.598998070095;5765.740541675551;5683.431656834284;5691.544676425793;5695.971520142539;5700.890670451793;5704.3725190228215;5521.972390138184;5579.218909352613;5596.216529685766;5635.9436405635415;5660.9337591777885;5333.839984800462;5338.626296159232;5624.578156638187;5676.545876329801;5719.628224165416;5589.524890384363;5608.831735047955;5612.803551875665;5627.493525582717;5637.830865074055 07:25:50 INFO - PROCESS | 12813 | |5;yui.html;756.9924300756992;774.2876553570715;825.8802473641322;843.4180415513296;862.0646598440692;15160.241987900603;15831.208439578018;15864.36542538297;15916.363345466181;15970.281337339818;14457.132572045659;14545.854541454608;14570.635734106652;14584.635384115403;14591.78112328316;693.4175292754086;799.2686691677109;835.757630287216;847.3560094328293;855.448235887852;836.1095433363448;843.5487014447272;844.2022288936957;847.4576271186446;847.6016272351985;879.6349514951288;891.5854127830557;891.674538793339;898.9101089890992;900.9279257659405;728.111703721459;893.7899593595467;898.3621628643671;911.6581282019242;912.7261821453603;654.456800855289;656.6585375604707;657.3098246840798;657.3131078023476;657.6645910585588;14794.704105917928;14818.33317500715;14833.92583037078;14854.628634284121;14857.777133343008;13871.098378605458;14544.70910581793;14567.927160364236;14580.635484112981;14666.706665866623;11354.602588909349;11471.598494052669;11535.711607209803;11548.3648399338;11808.763824723543;3795.449659799314;3819.388897776342;3887.5140607424073;3968.603139686037;3971.860984865516;54075;54329;56168.71915640437;56206.718966405315;56218.718906405615;11267.15496337778;11336.752957174704;11403.657890263139;11450.770984580262;11573.18987670855;20722.274720385016;20808.37574872756;20825.79174208269;20849.58300833975;20899.582008359746;8212.958935205286;8618.267447267053;8645.481271123752;8784.121587841293;8792.29661627081;4632.328312394684;4661.091087238024;4751.809927602857;4862.5380588844;4906.9263961040615;3435.1412146963257;3530.3645343838357;3532.823358832048;3539.1152211947015;3539.2213712983103;630.2059405149545;642.1010585180691;647.304148040858;661.2924171136933;663.7146027208347;731.2760367236503;742.2503271695626;746.4327111395379;754.7396148328818;765.7052034966578;9631.422114673142;9743.707688769347;9781.510924453749;9841.704748857546;9849.359791613522;742.6843591473602;749.8612756640039;765.2997507280776;766.9118051424047;769.630577322877;4370.590939067532;4577.427821522309;4591.563801438858;4621.468531118897;4635.096156249567;53319.73340133352;54659.453405465436;55528.72235638796;55860.72069639707;56242.437575624535;56646.15030774542;57975.71012144996;58166.4183358161;58836.70581647065;60017.39982600205;52050.47949520456;52893.206601901;53538.19692704535;54534.45465545373;55598.72200639052;8750.256228220673;8781.91218087811;8794.29645628361;8826.24976876947;9329.393589416937;31675;34602.307953841286;35197.47203791894;36058.27883442264;36644.26711465809;46963;48405;48992.265116024;49190.75404623097;49538.25692614542;11998.880011200126;12141.39293035327;12702.555410560586;12719.745605088032;12957.237641991385;14031.719365612835;14093.859061409255;14418.206998615176;14583.19792411385;14653.780193296896;13552.79670804919;13682.31588420555;13729;14200.502982395567;14478.638034049232;12666.303353315287;12755.87244127547;12832.486700531874;12970.740585188054;13299.002574807122;11802.763944720886;12148.21036632616;12299.87700123023;13277.136986096259;13817.378217980015;10784.676459706221;10919.781604368027;11348.943255283672;11695.473703683525;11800.881991180322;11313.434328283718;11476.942615286871;11569.247998880046;11703.531858725555;11815.82276265874 07:25:50 INFO - PROCESS | 12813 | __end_tp_report 07:25:50 INFO - PROCESS | 12813 | __start_cc_report 07:25:50 INFO - PROCESS | 12813 | _x_x_mozilla_cycle_collect,1068 07:25:50 INFO - PROCESS | 12813 | __end_cc_report 07:25:50 INFO - PROCESS | 12813 | __startTimestamp1463063150509__endTimestamp 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | ------- Summary: start ------- 07:25:50 INFO - PROCESS | 12813 | Number of tests: 6 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | [#0] dojo.html Cycles:165 Average:5428.45 Median:5600.22 stddev:630.35 (11.3%) stddev-sans-first:607.28 07:25:50 INFO - PROCESS | 12813 | Values: 3187.8 3197.5 3227.0 3254.8 3276.8 5282.8 5393.9 5433.9 5547.8 5568.7 5476.0 5514.3 5521.8 5549.1 5559.7 4870.7 4911.3 5048.7 5080.2 5085.4 4837.6 5023.1 5024.7 5048.0 5106.3 4869.8 4897.2 4912.7 4933.4 4976.2 4896.8 4911.0 4983.7 4984.2 5043.0 4788.4 4923.7 4962.9 4969.3 5030.5 5245.0 5361.3 5400.1 5420.8 5462.1 5849.8 5865.4 5904.5 5910.2 5960.7 5957.5 5971.7 5971.9 6001.0 6026.9 5269.2 5435.9 5456.3 5482.3 5579.7 6024.5 6077.9 6078.9 6081.2 6084.1 5591.1 5795.7 5796.0 5828.9 5847.1 5816.7 5817.4 5848.6 5872.4 5897.6 5773.2 5845.1 5854.2 5858.8 5862.0 5600.2 5637.6 5835.4 5862.1 5889.1 4371.1 4378.3 4394.9 4395.1 4430.9 3912.4 3914.8 3916.6 3954.7 3965.6 5313.0 5471.7 5504.6 5508.6 5512.8 5800.7 5833.0 5837.3 5940.1 5968.9 5513.2 5521.5 5523.2 5537.8 5544.1 4894.2 4897.3 4902.5 4904.5 4956.5 5819.1 6030.2 6063.4 6083.6 6125.9 5962.0 5965.0 5965.0 5966.3 5968.6 5802.2 6073.8 6118.1 6137.7 6140.8 5720.5 5733.1 5752.0 5758.4 5806.5 5868.8 5873.0 5885.9 5887.6 5890.5 5480.6 5636.6 5718.2 5730.2 5742.8 5662.0 5744.3 5745.4 5753.3 5799.4 5650.9 5663.9 5664.4 5669.2 5670.6 5545.7 5667.8 5698.3 5779.6 5791.1 5477.2 5528.1 5552.6 5568.5 5715.7 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | [#1] ext.html Cycles:180 Average:59497.36 Median:36698.27 stddev:111810.09 (304.7%) stddev-sans-first:112036.31 07:25:50 INFO - PROCESS | 12813 | Values: 603.2 644.0 647.9 648.8 661.7 30460.5 31016.0 43214.4 43364.8 44551.8 42339.8 42850.6 42853.8 44720.6 44737.1 8813.6 8972.7 8978.5 8989.7 8989.7 7951.2 10414.4 10685.6 10692.1 10692.5 10369.0 10495.9 10496.1 10760.5 10770.4 10598.9 10616.7 10624.3 10676.9 10702.9 10416.1 10491.5 10515.2 10517.4 10525.1 44089.0 44392.3 44441.6 44475.6 44476.0 3553.6 3598.6 3739.0 3989.0 4265.7 33337.2 33385.0 34254.0 34307.1 34325.3 4350.0 4491.5 4504.5 4509.7 4516.9 331858.0 333092.0 333504.3 335223.0 335481.0 65845.7 66264.7 66364.7 66460.3 66467.0 69541.7 69594.3 69627.3 69633.3 69645.3 17620.8 18096.4 18122.4 18137.4 18146.5 13718.0 13797.8 13807.6 13812.9 13814.6 19431.9 20464.9 20465.8 20533.2 20556.2 7444.4 7477.2 7574.2 7780.3 7844.2 998.2 1090.4 1107.2 1109.5 1127.2 32407.2 32541.5 32554.7 32839.0 32840.8 1107.4 1139.4 1165.2 1171.0 1176.6 16792.9 17149.9 17253.7 17411.8 17630.6 476605.0 482782.0 483192.0 483699.0 484017.0 58281.4 59227.0 59500.4 59567.7 59649.7 1316.9 1396.2 1420.3 1421.8 1425.6 32791.7 33726.3 33927.7 34078.3 34748.3 50184.2 57121.0 57912.0 58293.0 63459.0 437034.0 438829.0 440134.0 442740.0 443830.0 26197.9 29023.3 29055.0 29107.9 29532.6 48935.5 49161.5 49339.0 49622.3 49651.8 40781.2 40912.8 42073.0 42099.4 42991.6 40134.0 40158.8 40673.8 40795.0 41127.8 40796.8 40907.2 41381.0 41427.6 41601.8 35138.0 35812.1 36498.5 36678.6 36717.9 28039.6 28045.7 28060.4 28358.3 28440.4 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | [#2] jquery.html Cycles:180 Average:29292.11 Median:21822.73 stddev:81838.17 (375.0%) stddev-sans-first:82039.01 07:25:50 INFO - PROCESS | 12813 | Values: 412.3 412.3 413.2 420.2 427.8 13587.5 17843.3 18817.7 19570.2 19587.3 14500.9 16786.7 17054.8 17501.8 18223.6 5685.2 5802.7 5815.3 5824.8 5877.4 5762.3 5791.7 5830.5 5837.9 5845.9 4171.2 6091.2 6383.1 6416.3 6506.6 6412.1 6427.9 6448.3 6483.4 6511.7 5593.6 5718.7 5737.8 5747.5 5762.3 21777.5 21868.0 22082.9 22268.0 22458.1 2258.6 2364.6 2434.0 2451.5 2546.8 13202.9 14076.5 14237.4 14498.2 14566.3 1925.7 1974.1 1978.8 2007.8 2011.1 78905.0 81533.6 84042.0 85037.6 85155.1 10572.3 10576.2 10598.3 10815.4 10995.7 15243.8 15259.0 15282.0 15301.3 15306.3 7826.1 7890.6 7965.6 8055.8 8122.4 7440.5 7444.5 7476.9 7564.4 7681.5 5731.3 6187.0 6611.1 6622.3 6660.8 1842.7 1892.9 1916.1 1948.3 1955.6 977.8 1066.4 1073.7 1115.7 1203.7 18184.0 18612.4 19095.4 19134.2 19292.3 1043.6 1076.5 1097.1 1097.5 1181.6 10342.4 11089.8 11171.5 11176.9 11185.7 488764.0 494332.0 494769.0 501160.0 501323.0 84962.6 85319.6 87042.6 102320.5 103602.0 1062.0 1074.7 1100.8 1104.8 1107.5 16577.1 16586.3 16868.9 17250.8 17378.5 40844.2 41465.0 41770.8 42155.8 43594.6 59572.7 60078.0 60490.0 60581.7 61905.3 8295.3 8643.5 8746.7 8842.9 8873.4 8996.0 11155.7 12858.8 13522.4 13771.9 11096.2 11554.2 11594.5 11677.9 11701.0 10148.8 10435.7 11580.9 11650.7 11916.3 11162.7 11342.1 11466.9 11599.8 12081.2 8707.3 8777.2 8875.3 8885.9 8914.6 10229.2 11334.5 11545.9 11820.2 11897.6 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | [#3] mootools.html Cycles:180 Average:5889.61 Median:4828.20 stddev:3331.05 (69.0%) stddev-sans-first:3323.08 07:25:50 INFO - PROCESS | 12813 | Values: 1370.7 1529.4 1578.2 1766.9 1908.8 7885.1 8069.5 8091.4 8246.1 8262.0 8057.9 8197.9 8219.1 8235.4 8243.8 2058.5 2310.4 2325.3 2340.1 2381.4 2448.4 2475.7 2476.2 2477.0 2477.4 2374.2 2413.1 2414.1 2418.0 2418.8 1965.9 2214.2 2270.6 2277.9 2282.2 2323.1 2331.9 2339.8 2342.0 2345.1 8894.9 8968.0 8968.9 8981.4 8986.6 5410.3 5512.3 5558.1 5558.6 5572.6 3321.1 3406.8 3420.2 3524.8 3576.3 1186.6 1205.7 1219.1 1247.9 1261.7 12442.3 12547.9 12579.2 12580.3 12591.7 4654.6 4720.4 4809.9 4846.5 4849.5 9792.4 9807.2 9816.2 9844.9 9845.3 5376.1 5419.2 5462.6 5484.9 5485.2 4346.6 4993.9 5095.4 5110.7 5148.5 3919.9 3967.9 4004.4 4047.1 4068.9 1186.8 1197.0 1197.2 1200.2 1212.3 1030.5 1034.5 1037.7 1043.1 1052.6 6129.6 6265.7 6333.1 6364.5 6485.4 463.0 463.2 487.4 490.0 499.4 4281.5 4356.9 4372.2 4387.0 4423.8 10973.1 11160.2 11406.9 11406.9 11443.1 11125.3 11167.2 11267.4 11273.5 11280.3 8015.0 8139.3 8245.8 8423.1 8489.2 6203.3 6344.1 6393.7 6465.1 6545.2 10063.5 10117.9 10242.9 10406.9 10445.7 10416.2 10488.2 10489.8 10506.8 10623.4 7245.2 7728.8 7729.9 7933.6 8087.7 8806.6 8807.3 8838.6 8858.7 8873.6 7723.0 7883.7 8066.8 8130.1 8229.5 7576.1 7584.0 7609.7 7762.7 7922.2 6879.4 6951.0 7062.8 7077.5 7088.4 5516.4 5524.3 5605.1 5617.1 5702.9 5361.0 5437.8 5519.6 5571.0 5648.7 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | [#4] prototype.html Cycles:180 Average:4762.85 Median:5260.96 stddev:1370.67 (26.1%) stddev-sans-first:1333.54 07:25:50 INFO - PROCESS | 12813 | Values: 331.2 342.1 343.7 344.7 344.8 5319.9 5349.1 5374.5 5449.0 5483.1 5293.8 5328.8 5332.3 5352.6 5371.2 4170.4 4231.3 4242.9 4250.6 4283.8 4188.7 4270.3 4274.8 4277.8 4299.1 4238.9 4239.9 4247.9 4248.9 4290.7 3951.8 4102.9 4187.7 4264.4 4278.6 3763.4 3789.3 3795.7 3798.8 3804.0 5016.3 5191.5 5246.1 5255.4 5266.5 4680.9 4897.8 4909.0 4926.5 4936.6 5644.2 5699.6 5705.7 5706.5 5726.3 4000.9 4202.3 4232.5 4249.0 4259.0 5842.7 5859.4 5911.1 5934.6 5953.0 4314.0 4319.0 4341.4 4346.4 4349.9 4158.4 4394.3 4401.3 4410.3 4416.0 4904.8 4925.5 4945.6 4954.5 4963.5 5695.5 5722.7 5723.6 5741.9 5755.9 1479.5 1519.2 1532.8 1533.7 1534.2 979.4 991.0 995.9 1009.0 1013.0 4840.4 4897.6 4907.4 4907.6 4978.5 5174.0 5188.5 5207.9 5224.2 5228.2 4905.9 4921.8 5065.8 5230.8 5233.7 3104.3 3124.8 3133.3 3134.9 3137.8 6013.5 6021.5 6022.3 6050.4 6059.4 5727.4 5912.2 5924.8 5967.6 5973.2 5941.9 5969.9 5981.2 6004.7 6023.8 5649.2 5705.4 5817.3 5860.5 5865.7 5700.0 5718.6 5731.6 5749.8 5752.2 5986.3 6013.7 6021.7 6028.5 6041.1 4103.7 4283.4 4297.8 4306.6 4321.4 5768.7 5781.0 5794.7 5815.6 5851.0 5579.8 5703.0 5709.5 5728.6 5765.7 5683.4 5691.5 5696.0 5700.9 5704.4 5522.0 5579.2 5596.2 5635.9 5660.9 5333.8 5338.6 5624.6 5676.5 5719.6 5589.5 5608.8 5612.8 5627.5 5637.8 07:25:50 INFO - PROCESS | 12813 | 07:25:50 INFO - PROCESS | 12813 | [#5] yui.html Cycles:180 Average:15268.39 Median:4648.09 stddev:17245.14 (371.0%) stddev-sans-first:17259.08 07:25:50 INFO - PROCESS | 12813 | Values: 757.0 774.3 825.9 843.4 862.1 15160.2 15831.2 15864.4 15916.4 15970.3 14457.1 14545.9 14570.6 14584.6 14591.8 693.4 799.3 835.8 847.4 855.4 836.1 843.5 844.2 847.5 847.6 879.6 891.6 891.7 898.9 900.9 728.1 893.8 898.4 911.7 912.7 654.5 656.7 657.3 657.3 657.7 14794.7 14818.3 14833.9 14854.6 14857.8 13871.1 14544.7 14567.9 14580.6 14666.7 11354.6 11471.6 11535.7 11548.4 11808.8 3795.4 3819.4 3887.5 3968.6 3971.9 54075.0 54329.0 56168.7 56206.7 56218.7 11267.2 11336.8 11403.7 11450.8 11573.2 20722.3 20808.4 20825.8 20849.6 20899.6 8213.0 8618.3 8645.5 8784.1 8792.3 4632.3 4661.1 4751.8 4862.5 4906.9 3435.1 3530.4 3532.8 3539.1 3539.2 630.2 642.1 647.3 661.3 663.7 731.3 742.3 746.4 754.7 765.7 9631.4 9743.7 9781.5 9841.7 9849.4 742.7 749.9 765.3 766.9 769.6 4370.6 4577.4 4591.6 4621.5 4635.1 53319.7 54659.5 55528.7 55860.7 56242.4 56646.2 57975.7 58166.4 58836.7 60017.4 52050.5 52893.2 53538.2 54534.5 55598.7 8750.3 8781.9 8794.3 8826.2 9329.4 31675.0 34602.3 35197.5 36058.3 36644.3 46963.0 48405.0 48992.3 49190.8 49538.3 11998.9 12141.4 12702.6 12719.7 12957.2 14031.7 14093.9 14418.2 14583.2 14653.8 13552.8 13682.3 13729.0 14200.5 14478.6 12666.3 12755.9 12832.5 12970.7 13299.0 11802.8 12148.2 12299.9 13277.1 13817.4 10784.7 10919.8 11348.9 11695.5 11800.9 11313.4 11476.9 11569.2 11703.5 11815.8 07:25:50 INFO - PROCESS | 12813 | -------- Summary: end -------- 07:25:50 INFO - PROCESS | 12813 | 07:25:51 INFO - TEST-INFO | 12813: exit 0 07:25:51 INFO - TEST-OK | dromaeo_css | took 1112777ms 07:25:51 INFO - TEST-START | kraken 07:25:51 INFO - operating with platform_type : linux_ 07:25:51 INFO - Initialising browser for kraken test... 07:25:51 INFO - TEST-INFO | started process 12995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6_1GVJ/profile http://localhost:56663/getInfo.html) 07:25:54 INFO - PROCESS | 12995 | __metrics Screen width/height:1600/1200 07:25:54 INFO - PROCESS | 12995 | colorDepth:24 07:25:54 INFO - PROCESS | 12995 | Browser inner width/height: 1024/697 07:25:54 INFO - PROCESS | 12995 | __metrics 07:25:55 ERROR - PROCESS | 12995 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:25:56 INFO - TEST-INFO | 12995: exit 0 07:25:56 INFO - Browser initialized. 07:25:56 INFO - Running cycle 1/1 for kraken test... 07:25:56 INFO - TEST-INFO | started process 13078 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6_1GVJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:26:08 INFO - PROCESS | 13078 | RSS: Main: 152100864 07:26:08 INFO - PROCESS | 13078 | 07:26:10 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56663/tests/kraken/driver.html?testName=audio-beat-detection) 07:26:11 INFO - PROCESS | 13078 | RSS: Main: 208392192 07:26:11 INFO - PROCESS | 13078 | 07:26:14 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56663/tests/kraken/driver.html?testName=audio-dft) 07:26:15 INFO - PROCESS | 13078 | RSS: Main: 197345280 07:26:15 INFO - PROCESS | 13078 | 07:26:18 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56663/tests/kraken/driver.html?testName=audio-fft) 07:26:19 INFO - PROCESS | 13078 | RSS: Main: 264974336 07:26:19 INFO - PROCESS | 13078 | 07:26:21 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56663/tests/kraken/driver.html?testName=audio-oscillator) 07:26:21 INFO - PROCESS | 13078 | RSS: Main: 174301184 07:26:21 INFO - PROCESS | 13078 | 07:26:24 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56663/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:26:24 INFO - PROCESS | 13078 | RSS: Main: 177348608 07:26:24 INFO - PROCESS | 13078 | 07:26:27 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56663/tests/kraken/driver.html?testName=imaging-darkroom) 07:26:28 INFO - PROCESS | 13078 | RSS: Main: 213925888 07:26:28 INFO - PROCESS | 13078 | 07:26:32 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56663/tests/kraken/driver.html?testName=imaging-desaturate) 07:26:32 INFO - PROCESS | 13078 | RSS: Main: 212279296 07:26:32 INFO - PROCESS | 13078 | 07:26:35 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56663/tests/kraken/driver.html?testName=json-parse-financial) 07:26:36 INFO - PROCESS | 13078 | RSS: Main: 212910080 07:26:36 INFO - PROCESS | 13078 | 07:26:37 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56663/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:26:38 INFO - PROCESS | 13078 | RSS: Main: 202682368 07:26:38 INFO - PROCESS | 13078 | 07:26:40 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:26:40 INFO - PROCESS | 13078 | RSS: Main: 204759040 07:26:40 INFO - PROCESS | 13078 | 07:26:42 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:26:43 INFO - PROCESS | 13078 | RSS: Main: 214360064 07:26:43 INFO - PROCESS | 13078 | 07:26:46 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:26:46 INFO - PROCESS | 13078 | RSS: Main: 221761536 07:26:46 INFO - PROCESS | 13078 | 07:26:49 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:26:50 INFO - PROCESS | 13078 | RSS: Main: 219308032 07:26:50 INFO - PROCESS | 13078 | 07:26:51 INFO - PROCESS | 13078 | Cycle 1(1): loaded http://localhost:56663/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56663/tests/kraken/driver.html?testName=ai-astar) 07:26:52 INFO - PROCESS | 13078 | RSS: Main: 265043968 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | __start_tp_report 07:26:52 INFO - PROCESS | 13078 | _x_x_mozilla_page_load 07:26:52 INFO - PROCESS | 13078 | _x_x_mozilla_page_load_details 07:26:52 INFO - PROCESS | 13078 | |i|pagename|runs| 07:26:52 INFO - PROCESS | 13078 | |0;ai-astar;112;104;109;109;110;110;110;110;110;111 07:26:52 INFO - PROCESS | 13078 | |1;audio-beat-detection;158;122;154;213;125;156;152;124;190;125 07:26:52 INFO - PROCESS | 13078 | |2;audio-dft;160;159;162;159;160;161;162;161;162;161 07:26:52 INFO - PROCESS | 13078 | |3;audio-fft;74;76;75;160;75;76;76;74;141;74 07:26:52 INFO - PROCESS | 13078 | |4;audio-oscillator;91;92;155;91;92;92;119;92;91;125 07:26:52 INFO - PROCESS | 13078 | |5;imaging-gaussian-blur;117;117;116;116;117;116;117;116;117;116 07:26:52 INFO - PROCESS | 13078 | |6;imaging-darkroom;145;145;145;145;145;145;145;145;145;148 07:26:52 INFO - PROCESS | 13078 | |7;imaging-desaturate;95;95;95;95;94;94;95;95;95;95 07:26:52 INFO - PROCESS | 13078 | |8;json-parse-financial;56;56;58;56;56;56;56;109;54;54 07:26:52 INFO - PROCESS | 13078 | |9;json-stringify-tinderbox;58;60;59;60;60;60;59;60;59;59 07:26:52 INFO - PROCESS | 13078 | |10;stanford-crypto-aes;79;82;83;78;79;79;79;87;79;79 07:26:52 INFO - PROCESS | 13078 | |11;stanford-crypto-ccm;126;123;122;122;121;122;122;127;184;130 07:26:52 INFO - PROCESS | 13078 | |12;stanford-crypto-pbkdf2;167;168;168;166;169;165;166;166;168;173 07:26:52 INFO - PROCESS | 13078 | |13;stanford-crypto-sha256-iterative;64;64;69;63;65;63;66;62;64;67 07:26:52 INFO - PROCESS | 13078 | __end_tp_report 07:26:52 INFO - PROCESS | 13078 | __start_cc_report 07:26:52 INFO - PROCESS | 13078 | _x_x_mozilla_cycle_collect,1853 07:26:52 INFO - PROCESS | 13078 | __end_cc_report 07:26:52 INFO - PROCESS | 13078 | __startTimestamp1463063212245__endTimestamp 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | ------- Summary: start ------- 07:26:52 INFO - PROCESS | 13078 | Number of tests: 14 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:2.12 (1.9%) stddev-sans-first:2.05 07:26:52 INFO - PROCESS | 13078 | Values: 112.0 104.0 109.0 109.0 110.0 110.0 110.0 110.0 110.0 111.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:155.00 stddev:30.48 (19.7%) stddev-sans-first:32.25 07:26:52 INFO - PROCESS | 13078 | Values: 158.0 122.0 154.0 213.0 125.0 156.0 152.0 124.0 190.0 125.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 07:26:52 INFO - PROCESS | 13078 | Values: 160.0 159.0 162.0 159.0 160.0 161.0 162.0 161.0 162.0 161.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#3] audio-fft Cycles:10 Average:90.10 Median:75.00 stddev:32.16 (42.9%) stddev-sans-first:33.58 07:26:52 INFO - PROCESS | 13078 | Values: 74.0 76.0 75.0 160.0 75.0 76.0 76.0 74.0 141.0 74.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:91.50 stddev:21.98 (24.0%) stddev-sans-first:22.81 07:26:52 INFO - PROCESS | 13078 | Values: 91.0 92.0 155.0 91.0 92.0 92.0 119.0 92.0 91.0 125.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 07:26:52 INFO - PROCESS | 13078 | Values: 117.0 117.0 116.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.95 (0.7%) stddev-sans-first:1.00 07:26:52 INFO - PROCESS | 13078 | Values: 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 148.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:26:52 INFO - PROCESS | 13078 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:56.00 stddev:16.87 (30.1%) stddev-sans-first:17.79 07:26:52 INFO - PROCESS | 13078 | Values: 56.0 56.0 58.0 56.0 56.0 56.0 56.0 109.0 54.0 54.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.53 07:26:52 INFO - PROCESS | 13078 | Values: 58.0 60.0 59.0 60.0 60.0 60.0 59.0 60.0 59.0 59.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.00 stddev:2.80 (3.5%) stddev-sans-first:2.92 07:26:52 INFO - PROCESS | 13078 | Values: 79.0 82.0 83.0 78.0 79.0 79.0 79.0 87.0 79.0 79.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:124.50 stddev:19.23 (15.4%) stddev-sans-first:20.34 07:26:52 INFO - PROCESS | 13078 | Values: 126.0 123.0 122.0 122.0 121.0 122.0 122.0 127.0 184.0 130.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:168.00 stddev:2.27 (1.4%) stddev-sans-first:2.40 07:26:52 INFO - PROCESS | 13078 | Values: 167.0 168.0 168.0 166.0 169.0 165.0 166.0 166.0 168.0 173.0 07:26:52 INFO - PROCESS | 13078 | 07:26:52 INFO - PROCESS | 13078 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.50 stddev:2.11 (3.3%) stddev-sans-first:2.22 07:26:52 INFO - PROCESS | 13078 | Values: 64.0 64.0 69.0 63.0 65.0 63.0 66.0 62.0 64.0 67.0 07:26:52 INFO - PROCESS | 13078 | -------- Summary: end -------- 07:26:52 INFO - PROCESS | 13078 | 07:26:53 INFO - TEST-INFO | 13078: exit 0 07:26:53 INFO - TEST-OK | kraken | took 61967ms 07:26:53 INFO - SUITE-END | took 1174s 07:26:53 INFO - Completed test suite (00:19:34) 07:26:53 INFO - javascript benchmark 07:26:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3187.7609179311544, 3197.4724170511868, 3226.967730322696, 3254.7558933079995, 3276.83615819209, 5282.841514754554, 5393.946060539393, 5433.945660543393, 5547.7780888764455, 5568.693721845297, 5476.0, 5514.338279406476, 5521.806736764214, 5549.084401073828, 5559.749811258493, 4870.683405578626, 4911.263310503436, 5048.7223202723835, 5080.212567052113, 5085.389753229618, 4837.588804951583, 5023.120953833065, 5024.748762561876, 5048.0, 5106.310648062501, 4869.756512174395, 4897.240927656219, 4912.7297998610065, 4933.40799104108, 4976.2286845539, 4896.828610998634, 4911.042346742387, 4983.72589507577, 4984.177610694231, 5043.041822053798, 4788.449328327218, 4923.704577725348, 4962.87592810179, 4969.329140566049, 5030.522100400456, 5244.973775131138, 5361.276227709248, 5400.081986062379, 5420.83737487876, 5462.071447853835, 5849.824505264849, 5865.442782935615, 5904.498117660004, 5910.231669882931, 5960.7019649017375, 5957.523398128183, 5971.731272092767, 5971.850703732398, 6001.039833626599, 6026.939730602681, 5269.209618557209, 5435.891282174374, 5456.345238571357, 5482.314710661168, 5579.721013949286, 6024.487918526975, 6077.9392206078255, 6078.908816367758, 6081.17904082952, 6084.056971169476, 5591.07747221712, 5795.710214489259, 5796.0, 5828.854278642983, 5847.1229315603, 5816.738246778905, 5817.447342502455, 5848.619839710405, 5872.383399742966, 5897.616654917417, 5773.191753154561, 5845.064789633639, 5854.151148083504, 5858.794942177004, 5862.0, 5600.215969764236, 5637.577181711388, 5835.35811060783, 5862.062070068769, 5889.057750759858, 4371.147626212922, 4378.255696531597, 4394.890127746768, 4395.077033822933, 4430.867073987721, 3912.374020156761, 3914.8042597869994, 3916.5887581804072, 3954.7231693781737, 3965.5836137205756, 5313.043652142652, 5471.7264136793, 5504.6421982571, 5508.641938274, 5512.751926163332, 5800.6809625471005, 5833.0375488044, 5837.29952405714, 5940.138679891392, 5968.910466343008, 5513.2005859150195, 5521.503064724114, 5523.171524271311, 5537.8061767837935, 5544.140658197985, 4894.19245824435, 4897.289892965571, 4902.534259245366, 4904.4605093439695, 4956.4547899731, 5819.098039803837, 6030.216071910623, 6063.363346848518, 6083.604565703304, 6125.877482450326, 5961.970190149022, 5965.015772397506, 5965.045592705146, 5966.343702192842, 5968.582199246185, 5802.1586869902785, 6073.817785466443, 6118.082287656887, 6137.662428566472, 6140.815775526741, 5720.48515633595, 5733.082706766897, 5752.022156233366, 5758.366579676316, 5806.506446952057, 5868.765249390148, 5873.0, 5885.911711324419, 5887.646741195542, 5890.4993075589055, 5480.588955828409, 5636.633618814764, 5718.199452076629, 5730.22641943349, 5742.827715168552, 5662.0, 5744.253247078021, 5745.368009519033, 5753.252077229933, 5799.449052340021, 5650.943490565206, 5663.915041274302, 5664.433556644302, 5669.234653321749, 5670.631408958405, 5545.694986775766, 5667.801626943037, 5698.287714035746, 5779.566532509994, 5791.102379131242, 5477.178423236546, 5528.06022976087, 5552.583556233218, 5568.470995255444, 5715.657060576379], "value": 5385.764401687573, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.2067830802495, 643.9792928208788, 647.9319671434499, 648.8248172993295, 661.6526323680071, 30460.54309185364, 31016.0, 43214.35178472318, 43364.78317608415, 44551.77724111383, 42339.78830105853, 42850.571494285046, 42853.7857310713, 44720.552794472045, 44737.10525789482, 8813.647454101829, 8972.730818075435, 8978.50618215998, 8989.685361012365, 8989.730308090735, 7951.165127661599, 10414.375137491776, 10685.572577096946, 10692.144628429707, 10692.51883665233, 10369.0, 10495.895041049605, 10496.107830834388, 10760.515776790064, 10770.353778773298, 10598.947005264963, 10616.681499555025, 10624.309419887682, 10676.893231067668, 10702.892971070307, 10416.114630256438, 10491.475426228659, 10515.211359148094, 10517.368957862553, 10525.10536604382, 44089.0, 44392.3341149883, 44441.55558444407, 44475.55524444747, 44476.0, 3553.626869178725, 3598.6401359864067, 3739.046543131511, 3988.980055099706, 4265.7014009019385, 33337.16657083568, 33385.0, 34254.0, 34307.14232144191, 34325.313493729984, 4349.978250108761, 4491.517799126281, 4504.48198457175, 4509.684322097454, 4516.864494065183, 331858.0, 333092.0, 333504.3324783385, 335223.0, 335481.0, 65845.67077164583, 66264.6686766563, 66364.6681766588, 66460.33539664638, 66467.0, 69541.65229173822, 69594.3040569598, 69627.3037269631, 69633.3036669637, 69645.30354696489, 17620.82379176192, 18096.371326580673, 18122.365717199835, 18137.36519221821, 18146.45560633183, 13718.0, 13797.793033104512, 13807.585772426843, 13812.86187138136, 13814.585562433143, 19431.902840485705, 20464.897675511525, 20465.795342046687, 20533.17867285292, 20556.17775288972, 7444.367228785614, 7477.1775104738435, 7574.166841647414, 7780.299773020455, 7844.176361482077, 998.1665309466531, 1090.3512410115968, 1107.2028139739375, 1109.4563663804838, 1127.1658972360394, 32407.189820254684, 32541.51187732186, 32554.674453255637, 32839.0, 32840.835795820865, 1107.374333501569, 1139.4132022008673, 1165.2425923149851, 1170.9590164344377, 1176.5940750441255, 16792.908460950242, 17149.91425042867, 17253.741193882102, 17411.825881741275, 17630.647387052442, 476605.0, 482782.0, 483192.0, 483699.0, 484017.0, 58281.4171858276, 59227.0, 59500.40499595035, 59567.702161488916, 59649.70175149097, 1316.8683131686946, 1396.2111407055183, 1420.31824724133, 1421.7938398932097, 1425.600831767107, 32791.67208327886, 33726.325473490884, 33927.66072339344, 34078.318433631684, 34748.30503389968, 50184.24723629221, 57121.0, 57912.0, 58293.0, 63459.0, 437034.0, 438829.0, 440134.0, 442740.0, 443830.0, 26197.869010654824, 29023.27441813887, 29055.0, 29107.85446072756, 29532.557011644414, 48935.5106448931, 49161.50838491712, 49339.0, 49622.25566616576, 49651.75174124106, 40781.184376311714, 40912.79543602263, 42073.0, 42099.368509472995, 42991.57008429876, 40134.0, 40158.79920600378, 40673.79663101665, 40795.0, 41127.794361028005, 40796.79601601973, 40907.1818563633, 41381.0, 41427.58572414358, 41601.79199103985, 35138.0, 35812.104697382776, 36498.45252321164, 36678.63321366859, 36717.89846304615, 28039.579406308512, 28045.719542804312, 28060.438791223653, 28358.291042724097, 28440.431191375643], "value": 21037.647123433337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.25382058474185, 412.3381971935046, 413.23963906412206, 420.15968063872253, 427.77969345786903, 13587.456501739944, 17843.28626854926, 18817.717734233964, 19570.21719131235, 19587.314443994463, 14500.854991450082, 16786.66426671466, 17054.82945170548, 17501.82498175018, 18223.635527289447, 5685.1756495308255, 5802.738876750535, 5815.302163740357, 5824.767009319622, 5877.412258774131, 5762.250907382056, 5791.681457519835, 5830.475257226849, 5837.8832423351505, 5845.912311315334, 4171.20747058058, 6091.208142941434, 6383.138276332682, 6416.3262857400005, 6506.642134682591, 6412.070249813797, 6427.871442571146, 6448.290688024314, 6483.448906842924, 6511.674416279167, 5593.608447408683, 5718.714064296769, 5737.827865164052, 5747.540196784247, 5762.250907382014, 21777.455563610878, 21868.0, 22082.88958555213, 22268.0, 22458.101675932943, 2258.6160352739907, 2364.6285154610287, 2433.9878300608384, 2451.534208500397, 2546.7580579844885, 13202.933985330108, 14076.507322243775, 14237.430502779876, 14498.202598857059, 14566.344514496874, 1925.749652545174, 1974.1412485568824, 1978.8317992970472, 2007.7691065527506, 2011.1452632631074, 78905.0, 81533.59233203855, 84042.0, 85037.57481212555, 85155.14844851533, 10572.304062464176, 10576.153907687367, 10598.25812193139, 10815.35107893529, 10995.670129896116, 15243.771343429857, 15259.0, 15282.0, 15301.311440985182, 15306.311215995307, 7826.099998500245, 7890.566018869017, 7965.561894095765, 8055.817951908964, 8122.350211983027, 7440.516366436164, 7444.516106453063, 7476.887846682304, 7564.394848412114, 7681.462297639224, 5731.254936858182, 6187.0, 6611.082785992105, 6622.337766223339, 6660.800175994728, 1842.677531431994, 1892.8674992750375, 1916.1377380178976, 1948.2888745608034, 1955.599102184049, 977.8191034658469, 1066.3548553125402, 1073.6671631794168, 1115.70991542198, 1203.7111093337535, 18184.0, 18612.44162675122, 19095.427137185907, 19134.234630614897, 19292.32476863303, 1043.5877828257946, 1076.4994277660926, 1097.138746084336, 1097.5390336058874, 1181.5746331320718, 10342.37945723259, 11089.778204435866, 11171.497282622302, 11176.944115279533, 11185.66443006711, 488764.0, 494332.0, 494769.0, 501160.0, 501323.0, 84962.57518712491, 85319.57340213259, 87042.56478717693, 102320.48839755902, 103602.0, 1061.9575216991389, 1074.7259448840653, 1100.834874768791, 1104.7624760676492, 1107.4850194659523, 16577.064051364585, 16586.25361858695, 16868.915655421646, 17250.827491724922, 17378.47864564065, 40844.1831163381, 41465.0, 41770.791146044074, 42155.789221053696, 43594.56405435905, 59572.70213648904, 60078.0, 60490.0, 60581.69709151426, 61905.285682859176, 8295.253427191581, 8643.524606146722, 8746.68799680053, 8842.867356989778, 8873.378863479495, 8996.0, 11155.665330040112, 12858.807117893051, 13522.391492382669, 13771.931140344635, 11096.167787415814, 11554.191206615456, 11594.47824847867, 11677.88322116768, 11701.0, 10148.79702405933, 10435.68692939213, 11580.884191158317, 11650.708732281422, 11916.344601047025, 11162.720931976766, 11342.149338799458, 11466.885331146916, 11599.76800464003, 12081.154319197572, 8707.346948978979, 8777.210051095431, 8875.334349923653, 8885.911140888767, 8914.643414263357, 10229.232807539316, 11334.546618135511, 11545.942270288931, 11820.172587918763, 11897.643070707893], "value": 9413.631874877032, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.6573356660836, 1529.3653133949415, 1578.1556867076115, 1766.9381571644994, 1908.8377487913526, 7885.0932142803595, 8069.4754840935375, 8091.393145514088, 8246.134155913633, 8262.049864265611, 8057.879131813028, 8197.877031844511, 8219.095899451071, 8235.423520353577, 8243.752687419386, 2058.4853786553363, 2310.4339436837954, 2325.2791634593245, 2340.0756701103055, 2381.368937231635, 2448.4123810285582, 2475.7029156501244, 2476.19523654812, 2477.02157647729, 2477.4301910560494, 2374.1809075868796, 2413.095089341497, 2414.070582825616, 2417.9637305440433, 2418.818588605862, 1965.931192408266, 2214.247155966979, 2270.579942710596, 2277.874716890587, 2282.155602427096, 2323.0707716913203, 2331.8717470539113, 2339.8245131615026, 2341.988290058539, 2345.0502546468706, 8894.866577001285, 8967.955160224223, 8968.865467018, 8981.371304008722, 8986.595603197871, 5410.323709536308, 5512.3385193777, 5558.082916318803, 5558.610897237195, 5572.637778544382, 3321.086701157177, 3406.812625305607, 3420.1620602952435, 3524.8237588120746, 3576.302620988908, 1186.6321440353518, 1205.6624145239348, 1219.1465973818363, 1247.8876901078856, 1261.735035642516, 12442.253464792142, 12547.93726031373, 12579.245245285312, 12580.30808305552, 12591.685207869785, 4654.604358629555, 4720.409948756405, 4809.927851082237, 4846.466888642247, 4849.466558678542, 9792.363496372713, 9807.166390856715, 9816.214702823758, 9844.9015509844, 9845.261605379625, 5376.086065368897, 5419.214213939035, 5462.562994960394, 5484.91772623411, 5485.177223416519, 4346.565343465628, 4993.875153121201, 5095.414027386824, 5110.66780659256, 5148.459411761786, 3919.862804801818, 3967.940480892789, 4004.3993400990084, 4047.1096358801015, 4068.898277543085, 1186.8252424763723, 1196.970075748096, 1197.21582363552, 1200.2138599217526, 1212.345333519907, 1030.4538594544904, 1034.4879284754095, 1037.7457522906784, 1043.12377602814, 1052.6105341023717, 6129.570930034854, 6265.686715664198, 6333.113364129025, 6364.522660800459, 6485.416312531895, 463.0159765405222, 463.15243105118225, 487.373724763677, 489.9857295398562, 499.40570720842084, 4281.464816897887, 4356.934645980313, 4372.23485889968, 4386.956130438654, 4423.8230470781455, 10973.122150227962, 11160.218784685156, 11406.885931140749, 11406.942965285121, 11443.084553235722, 11125.276857004268, 11167.162462815322, 11267.436628168558, 11273.492692828842, 11280.32318060919, 8014.998125234346, 8139.348852091818, 8245.79385515367, 8423.073461919306, 8489.235968762841, 6203.348648391854, 6344.111824344688, 6393.680315984275, 6465.0948867157695, 6545.181852268466, 10063.547140378554, 10117.949410252902, 10242.897571024194, 10406.947965260126, 10445.738856528647, 10416.166706663296, 10488.160947124059, 10489.790204196026, 10506.789864202827, 10623.415712135908, 7245.239249878687, 7728.806779830549, 7729.9227007729205, 7933.60331983387, 8087.716929907425, 8806.559672016245, 8807.339449541181, 8838.558072096497, 8858.689945851864, 8873.645054198018, 7723.0, 7883.724069657764, 8066.7579972600915, 8130.105688374392, 8229.547374894439, 7576.1287451941525, 7584.0, 7609.6575654096805, 7762.728304509315, 7922.247386498273, 6879.449644028566, 6950.965245173742, 7062.752803651848, 7077.5399599025895, 7088.397486213524, 5516.365617953987, 5524.309461317335, 5605.131204663284, 5617.101263797773, 5702.942970570241, 5361.035013697572, 5437.809676661298, 5519.613627046068, 5570.997220500187, 5648.689322087161], "value": 4652.527334465061, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.20180365319584, 342.09004049229054, 343.66321005414693, 344.7242206235012, 344.8430515620093, 5319.893602127955, 5349.117395629726, 5374.462553744628, 5448.972755136224, 5483.122700367942, 5293.788248470057, 5328.813491527797, 5332.253484512162, 5352.625316227865, 5371.167469042305, 4170.374443833419, 4231.322988321869, 4242.851500197494, 4250.638695710868, 4283.828646854137, 4188.706790524665, 4270.252705776492, 4274.807633656477, 4277.8288868445225, 4299.118680670471, 4238.851640192594, 4239.894002649943, 4247.895547157746, 4248.940762650886, 4290.742555446684, 3951.7826519541413, 4102.938455923164, 4187.664986801049, 4264.381664658638, 4278.572142785728, 3763.397856342972, 3789.2800367930004, 3795.6773674237725, 3798.810059497014, 3803.9809800951093, 5016.347874776256, 5191.480851914816, 5246.137621144477, 5255.395629502618, 5266.525372895595, 4680.882977925545, 4897.828575999859, 4909.0, 4926.531979461981, 4936.580390666762, 5644.238027866268, 5699.601027928046, 5705.657660540381, 5706.54347652191, 5726.284214473191, 4000.939750965967, 4202.2646036943415, 4232.534421213695, 4248.957510424887, 4258.978705106455, 5842.737076831553, 5859.3554708981555, 5911.083782013795, 5934.643921364732, 5952.970235148797, 4313.956860431418, 4318.978405107954, 4341.39220509129, 4346.413559050924, 4349.933781453784, 4158.4178215049915, 4394.2749446340995, 4401.273789824707, 4410.33844923264, 4415.977920110379, 4904.828331008398, 4925.458199598042, 4945.554900059012, 4954.504549545089, 4963.528464795839, 5695.5443564514735, 5722.656640601577, 5723.624695751299, 5741.913871291934, 5755.913661295084, 1479.5344345211495, 1519.187234829361, 1532.8007359043254, 1533.74693175627, 1534.1792141204403, 979.4221409368507, 990.9752256193652, 995.9442990430167, 1008.9798204035878, 1012.9993263304914, 4840.443349014909, 4897.583705385011, 4907.3620429343955, 4907.55831975124, 4978.47725988766, 5174.008006917364, 5188.495336352491, 5207.895842083137, 5224.164133738584, 5228.163493840967, 4905.87735306613, 4921.803127874917, 5065.797368105308, 5230.764615592308, 5233.712145832016, 3104.348086901776, 3124.7657175415584, 3133.310671652233, 3134.8902788402297, 3137.8431078445988, 6013.4888534474185, 6021.548383871227, 6022.307434644984, 6050.42520960508, 6059.424354686386, 5727.427257274239, 5912.231409916683, 5924.822255332347, 5967.641941483438, 5973.1936188614845, 5941.881162376728, 5969.910451343233, 5981.222441082718, 6004.699765011819, 6023.819285421444, 5649.20911072442, 5705.37396841898, 5817.301923769175, 5860.472557469848, 5865.736041878211, 5699.971500142473, 5718.599698021098, 5731.627444216113, 5749.8275051748515, 5752.223449834219, 5986.341502434641, 6013.729382177725, 6021.668808215415, 6028.457438830526, 6041.063635136561, 4103.692223083221, 4283.443152390169, 4297.806598703129, 4306.590873866978, 4321.351797230441, 5768.711564421678, 5781.046127388934, 5794.652320860761, 5815.621984570989, 5850.970745146247, 5579.804706835242, 5702.971485142548, 5709.486146246858, 5728.598998070095, 5765.740541675551, 5683.431656834284, 5691.544676425793, 5695.971520142539, 5700.890670451793, 5704.3725190228215, 5521.972390138184, 5579.218909352613, 5596.216529685766, 5635.9436405635415, 5660.9337591777885, 5333.839984800462, 5338.626296159232, 5624.578156638187, 5676.545876329801, 5719.628224165416, 5589.524890384363, 5608.831735047955, 5612.803551875665, 5627.493525582717, 5637.830865074055], "value": 4331.018815522236, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.9924300756992, 774.2876553570715, 825.8802473641322, 843.4180415513296, 862.0646598440692, 15160.241987900603, 15831.208439578018, 15864.36542538297, 15916.363345466181, 15970.281337339818, 14457.132572045659, 14545.854541454608, 14570.635734106652, 14584.635384115403, 14591.78112328316, 693.4175292754086, 799.2686691677109, 835.757630287216, 847.3560094328293, 855.448235887852, 836.1095433363448, 843.5487014447272, 844.2022288936957, 847.4576271186446, 847.6016272351985, 879.6349514951288, 891.5854127830557, 891.674538793339, 898.9101089890992, 900.9279257659405, 728.111703721459, 893.7899593595467, 898.3621628643671, 911.6581282019242, 912.7261821453603, 654.456800855289, 656.6585375604707, 657.3098246840798, 657.3131078023476, 657.6645910585588, 14794.704105917928, 14818.33317500715, 14833.92583037078, 14854.628634284121, 14857.777133343008, 13871.098378605458, 14544.70910581793, 14567.927160364236, 14580.635484112981, 14666.706665866623, 11354.602588909349, 11471.598494052669, 11535.711607209803, 11548.3648399338, 11808.763824723543, 3795.449659799314, 3819.388897776342, 3887.5140607424073, 3968.603139686037, 3971.860984865516, 54075.0, 54329.0, 56168.71915640437, 56206.718966405315, 56218.718906405615, 11267.15496337778, 11336.752957174704, 11403.657890263139, 11450.770984580262, 11573.18987670855, 20722.274720385016, 20808.37574872756, 20825.79174208269, 20849.58300833975, 20899.582008359746, 8212.958935205286, 8618.267447267053, 8645.481271123752, 8784.121587841293, 8792.29661627081, 4632.328312394684, 4661.091087238024, 4751.809927602857, 4862.5380588844, 4906.9263961040615, 3435.1412146963257, 3530.3645343838357, 3532.823358832048, 3539.1152211947015, 3539.2213712983103, 630.2059405149545, 642.1010585180691, 647.304148040858, 661.2924171136933, 663.7146027208347, 731.2760367236503, 742.2503271695626, 746.4327111395379, 754.7396148328818, 765.7052034966578, 9631.422114673142, 9743.707688769347, 9781.510924453749, 9841.704748857546, 9849.359791613522, 742.6843591473602, 749.8612756640039, 765.2997507280776, 766.9118051424047, 769.630577322877, 4370.590939067532, 4577.427821522309, 4591.563801438858, 4621.468531118897, 4635.096156249567, 53319.73340133352, 54659.453405465436, 55528.72235638796, 55860.72069639707, 56242.437575624535, 56646.15030774542, 57975.71012144996, 58166.4183358161, 58836.70581647065, 60017.39982600205, 52050.47949520456, 52893.206601901, 53538.19692704535, 54534.45465545373, 55598.72200639052, 8750.256228220673, 8781.91218087811, 8794.29645628361, 8826.24976876947, 9329.393589416937, 31675.0, 34602.307953841286, 35197.47203791894, 36058.27883442264, 36644.26711465809, 46963.0, 48405.0, 48992.265116024, 49190.75404623097, 49538.25692614542, 11998.880011200126, 12141.39293035327, 12702.555410560586, 12719.745605088032, 12957.237641991385, 14031.719365612835, 14093.859061409255, 14418.206998615176, 14583.19792411385, 14653.780193296896, 13552.79670804919, 13682.31588420555, 13729.0, 14200.502982395567, 14478.638034049232, 12666.303353315287, 12755.87244127547, 12832.486700531874, 12970.740585188054, 13299.002574807122, 11802.763944720886, 12148.21036632616, 12299.87700123023, 13277.136986096259, 13817.378217980015, 10784.676459706221, 10919.781604368027, 11348.943255283672, 11695.473703683525, 11800.881991180322, 11313.434328283718, 11476.942615286871, 11569.247998880046, 11703.531858725555, 11815.82276265874], "value": 7004.515053673372, "unit": "score"}], "name": "dromaeo_css", "value": 7293.7481300323925, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 154.0, 213.0, 125.0, 156.0, 152.0, 124.0, 190.0, 125.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 162.0, 159.0, 160.0, 161.0, 162.0, 161.0, 162.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 160.0, 75.0, 76.0, 76.0, 74.0, 141.0, 74.0], "value": 90.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 155.0, 91.0, 92.0, 92.0, 119.0, 92.0, 91.0, 125.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 148.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 56.0, 56.0, 56.0, 56.0, 109.0, 54.0, 54.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 83.0, 78.0, 79.0, 79.0, 79.0, 87.0, 79.0, 79.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 123.0, 122.0, 122.0, 121.0, 122.0, 122.0, 127.0, 184.0, 130.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 168.0, 166.0, 169.0, 165.0, 166.0, 166.0, 168.0, 173.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 69.0, 63.0, 65.0, 63.0, 66.0, 62.0, 64.0, 67.0], "value": 64.7, "unit": "score"}], "name": "kraken", "value": 1535.9, "alertThreshold": 2.0}]} 07:26:53 INFO - Return code: 0 07:26:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:26:53 ERROR - # TBPL SUCCESS # 07:26:53 INFO - Running post-action listener: _resource_record_post_action 07:26:53 INFO - ##### 07:26:53 INFO - ##### Finished run-tests step (success) 07:26:53 INFO - ##### 07:26:53 INFO - Running post-run listener: _resource_record_post_run 07:26:54 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 537104384; Read time: 20; Write time: 431820 07:26:54 INFO - TinderboxPrint: CPU usage
13.1% 07:26:54 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 07:26:54 INFO - TinderboxPrint: I/O write bytes / time
537,104,384 / 431,820 07:26:54 INFO - TinderboxPrint: CPU idle
8,274.1 (86.9%) 07:26:54 INFO - TinderboxPrint: CPU user
1,203.0 (12.6%) 07:26:54 INFO - TinderboxPrint: Swap in / out
0 / 0 07:26:54 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 244989952; Read time: 0; Write time: 252844 07:26:54 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 285917184; Read time: 20; Write time: 177464 07:26:54 INFO - Running post-run listener: _upload_blobber_files 07:26:54 INFO - Blob upload gear active. 07:26:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:26:54 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 07:26:54 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:26:54 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:26:55 INFO - (blobuploader) - INFO - Open directory for files ... 07:26:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:26:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:26:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:26:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:26:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:26:55 INFO - (blobuploader) - INFO - Done attempting. 07:26:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:26:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:26:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:26:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:26:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:26:56 INFO - (blobuploader) - INFO - Done attempting. 07:26:56 INFO - (blobuploader) - INFO - Iteration through files over. 07:26:56 INFO - Return code: 0 07:26:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:26:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:26:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82eca54579be87a366fa5651f308f478df09c1c764b5fe7030874e099ad261f680b887dde6ca85b926611b9e4fdbd7e5213dcc2d1034d6702ab725fadf0d60d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3f41356964e45efd2e5b25333a628af2aa1fc5c1b962957f218ab21bbedc9f1703ec2f0a0ec850acdd05058ed5411158f5ea194af3243841d16704e4edf6c14"} 07:26:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:26:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:26:56 INFO - Contents: 07:26:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82eca54579be87a366fa5651f308f478df09c1c764b5fe7030874e099ad261f680b887dde6ca85b926611b9e4fdbd7e5213dcc2d1034d6702ab725fadf0d60d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3f41356964e45efd2e5b25333a628af2aa1fc5c1b962957f218ab21bbedc9f1703ec2f0a0ec850acdd05058ed5411158f5ea194af3243841d16704e4edf6c14"} 07:26:56 INFO - Running post-run listener: copy_logs_to_upload_dir 07:26:56 INFO - Copying logs to upload dir... 07:26:56 INFO - mkdir: /builds/slave/test/build/upload/logs 07:26:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.059130 ========= master_lag: 244.91 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 54 secs) (at 2016-05-12 07:31:01.479614) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 mins, 47 secs) (at 2016-05-12 07:31:01.487297) ========= 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=0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82eca54579be87a366fa5651f308f478df09c1c764b5fe7030874e099ad261f680b887dde6ca85b926611b9e4fdbd7e5213dcc2d1034d6702ab725fadf0d60d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3f41356964e45efd2e5b25333a628af2aa1fc5c1b962957f218ab21bbedc9f1703ec2f0a0ec850acdd05058ed5411158f5ea194af3243841d16704e4edf6c14"} build_url:https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011337 build_url: 'https://queue.taskcluster.net/v1/task/EU5-rn-HS4C6aYdUbShcEg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82eca54579be87a366fa5651f308f478df09c1c764b5fe7030874e099ad261f680b887dde6ca85b926611b9e4fdbd7e5213dcc2d1034d6702ab725fadf0d60d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3f41356964e45efd2e5b25333a628af2aa1fc5c1b962957f218ab21bbedc9f1703ec2f0a0ec850acdd05058ed5411158f5ea194af3243841d16704e4edf6c14"}' ========= master_lag: 227.98 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 mins, 47 secs) (at 2016-05-12 07:34:49.477976) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:34:49.478377) ========= 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=0333a256a5e4f052382dd180000001b1-1463059574.536986-7072866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004822 ========= master_lag: 0.94 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 07:34:50.418452) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-05-12 07:34:50.421908) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-05-12 07:34:51.752581) ========= ========= Total master_lag: 473.90 =========