builder: date_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1457024779.74 results: success (0) buildid: 20160302200635 builduid: 6efb071dd86f47a1bdca869686093b38 revision: 07e13e514846833c01f65812d04b9c6a2f4b9f84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.741183) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.741785) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.742109) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.003778 basedir: '/builds/slave/test' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.758202) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.758504) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.771594) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.771923) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.003319 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.852505) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.852854) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.853277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:19.853584) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-03 09:06:20-- 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% 163K=0.07s 2016-03-03 09:06:20 (163 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.279036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.151705) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.152051) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020234 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.201944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.202376) ========= bash -c 'python archiver_client.py mozharness --repo projects/date --rev 07e13e514846833c01f65812d04b9c6a2f4b9f84 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/date --rev 07e13e514846833c01f65812d04b9c6a2f4b9f84 --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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-03 09:06:20,505 truncating revision to first 12 chars 2016-03-03 09:06:20,505 Setting DEBUG logging. 2016-03-03 09:06:20,505 attempt 1/10 2016-03-03 09:06:20,505 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/date/07e13e514846?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-03 09:06:20,789 unpacking tar archive at: date-07e13e514846/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.877872) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.878265) ========= echo 07e13e514846833c01f65812d04b9c6a2f4b9f84 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'07e13e514846833c01f65812d04b9c6a2f4b9f84'] 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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07e13e514846833c01f65812d04b9c6a2f4b9f84 program finished with exit code 0 elapsedTime=0.004701 script_repo_revision: '07e13e514846833c01f65812d04b9c6a2f4b9f84' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.929591) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.929932) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-03 09:06:20.940330) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-03-03 09:06:20.940655) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Date-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Date-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Date-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Date-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:06:21 INFO - MultiFileLogger online at 20160303 09:06:21 in /builds/slave/test 09:06:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Date-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Date-Non-PGO 09:06:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:06:21 INFO - {'append_to_log': False, 09:06:21 INFO - 'base_work_dir': '/builds/slave/test', 09:06:21 INFO - 'blob_upload_branch': 'Date-Non-PGO', 09:06:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:06:21 INFO - 'branch': 'Date-Non-PGO', 09:06:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:21 INFO - 'config_files': ('talos/linux_config.py',), 09:06:21 INFO - 'default_actions': ('clobber', 09:06:21 INFO - 'read-buildbot-config', 09:06:21 INFO - 'download-and-extract', 09:06:21 INFO - 'populate-webroot', 09:06:21 INFO - 'create-virtualenv', 09:06:21 INFO - 'install', 09:06:21 INFO - 'run-tests'), 09:06:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:21 INFO - 'download_minidump_stackwalk': True, 09:06:21 INFO - 'download_symbols': 'ondemand', 09:06:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:21 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:21 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:21 INFO - 'installer_path': 'installer.exe', 09:06:21 INFO - 'log_level': 'info', 09:06:21 INFO - 'log_name': 'talos', 09:06:21 INFO - 'log_to_console': True, 09:06:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:06:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:06:21 INFO - 'opt_config_files': (), 09:06:21 INFO - 'pip_index': False, 09:06:21 INFO - 'sps_profile': False, 09:06:21 INFO - 'sps_profile_interval': 0, 09:06:21 INFO - 'suite': 'dromaeojs', 09:06:21 INFO - 'system_bits': '32', 09:06:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:21 INFO - 'title': 'talos-linux64-ix-014', 09:06:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:21 INFO - 'use_talos_json': True, 09:06:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:06:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:21 INFO - 'work_dir': 'build'} 09:06:21 INFO - ##### 09:06:21 INFO - ##### Running clobber step. 09:06:21 INFO - ##### 09:06:21 INFO - Running pre-action listener: _resource_record_pre_action 09:06:21 INFO - Running main action method: clobber 09:06:21 INFO - rmtree: /builds/slave/test/build 09:06:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:06:22 INFO - Running post-action listener: _resource_record_post_action 09:06:22 INFO - ##### 09:06:22 INFO - ##### Running read-buildbot-config step. 09:06:22 INFO - ##### 09:06:22 INFO - Running pre-action listener: _resource_record_pre_action 09:06:22 INFO - Running main action method: read_buildbot_config 09:06:22 INFO - Using buildbot properties: 09:06:22 INFO - { 09:06:22 INFO - "project": "", 09:06:22 INFO - "product": "firefox", 09:06:22 INFO - "script_repo_revision": "production", 09:06:22 INFO - "builddir": "date_ubuntu64_hw_test-dromaeojs", 09:06:22 INFO - "repository": "", 09:06:22 INFO - "buildername": "Ubuntu HW 12.04 x64 date talos dromaeojs", 09:06:22 INFO - "buildid": "20160302200635", 09:06:22 INFO - "pgo_build": "False", 09:06:22 INFO - "basedir": "/builds/slave/test", 09:06:22 INFO - "buildnumber": 4, 09:06:22 INFO - "slavename": "talos-linux64-ix-014", 09:06:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:06:22 INFO - "platform": "ubuntu64_hw", 09:06:22 INFO - "scheduler": "tests-date-linux64-talos", 09:06:22 INFO - "branch": "date", 09:06:22 INFO - "repo_path": "projects/date", 09:06:22 INFO - "revision": "07e13e514846833c01f65812d04b9c6a2f4b9f84", 09:06:22 INFO - "stage_platform": "linux64", 09:06:22 INFO - "builduid": "6efb071dd86f47a1bdca869686093b38", 09:06:22 INFO - "slavebuilddir": "test" 09:06:22 INFO - } 09:06:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2. 09:06:22 INFO - Running post-action listener: _resource_record_post_action 09:06:22 INFO - ##### 09:06:22 INFO - ##### Running download-and-extract step. 09:06:22 INFO - ##### 09:06:22 INFO - Running pre-action listener: _resource_record_pre_action 09:06:22 INFO - Running main action method: download_and_extract 09:06:22 INFO - mkdir: /builds/slave/test/build/tests 09:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:22 INFO - https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:06:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/test_packages.json 09:06:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/test_packages.json 09:06:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:06:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:06:22 INFO - Downloaded 1412 bytes. 09:06:22 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:06:22 INFO - Using the following test package requirements: 09:06:22 INFO - {u'common': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip'], 09:06:22 INFO - u'cppunittest': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'firefox-46.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:06:22 INFO - u'gtest': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'firefox-46.0.en-US.linux-x86_64.gtest.tests.zip'], 09:06:22 INFO - u'jittest': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'jsshell-linux-x86_64.zip'], 09:06:22 INFO - u'mochitest': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'firefox-46.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:06:22 INFO - u'mozbase': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip'], 09:06:22 INFO - u'reftest': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'firefox-46.0.en-US.linux-x86_64.reftest.tests.zip'], 09:06:22 INFO - u'talos': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'firefox-46.0.en-US.linux-x86_64.talos.tests.zip'], 09:06:22 INFO - u'web-platform': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'firefox-46.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:06:22 INFO - u'webapprt': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip'], 09:06:22 INFO - u'xpcshell': [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', 09:06:22 INFO - u'firefox-46.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:06:22 INFO - Downloading packages: [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:22 INFO - https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:06:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip 09:06:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip 09:06:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip 09:06:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:06:23 INFO - Downloaded 23091872 bytes. 09:06:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:06:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:06:25 INFO - Return code: 0 09:06:25 INFO - Downloading packages: [u'firefox-46.0.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09: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')]} 09:06:25 INFO - https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:06:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip 09:06:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip 09:06:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip 09:06:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:06:26 INFO - Downloaded 23091872 bytes. 09:06:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:06:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:06:27 INFO - Return code: 0 09:06:27 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')]} 09:06:27 INFO - https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:06:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip 09:06:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip 09:06:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip 09:06:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:06:29 INFO - Downloaded 10706668 bytes. 09:06:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:06:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:06:29 INFO - Return code: 0 09:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:29 INFO - https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:06:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 09:06:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 09:06:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 09:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:06:31 INFO - Downloaded 52852456 bytes. 09:06:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 09:06:31 INFO - mkdir: /builds/slave/test/properties 09:06:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:06:31 INFO - Writing to file /builds/slave/test/properties/build_url 09:06:31 INFO - Contents: 09:06:31 INFO - build_url:https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 09:06:31 INFO - Running post-action listener: _resource_record_post_action 09:06:31 INFO - Running post-action listener: set_extra_try_arguments 09:06:31 INFO - ##### 09:06:31 INFO - ##### Running populate-webroot step. 09:06:31 INFO - ##### 09:06:31 INFO - Running pre-action listener: _resource_record_pre_action 09:06:31 INFO - Running main action method: populate_webroot 09:06:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:31 INFO - '--tppagecycles', 09:06:31 INFO - '7'], 09:06:31 INFO - 'tests': ['tsvgm']}}, 09:06:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:06:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:06:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:06:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'tests': ['damp', 'tps']}, 09:06:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['damp', 'tps']}, 09:06:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:06:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:31 INFO - 'other': {'tests': ['a11yr', 09:06:31 INFO - 'ts_paint', 09:06:31 INFO - 'tpaint', 09:06:31 INFO - 'sessionrestore', 09:06:31 INFO - 'sessionrestore_no_auto_restore']}, 09:06:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['a11yr', 09:06:31 INFO - 'ts_paint', 09:06:31 INFO - 'tpaint', 09:06:31 INFO - 'sessionrestore', 09:06:31 INFO - 'sessionrestore_no_auto_restore']}, 09:06:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['a11yr', 09:06:31 INFO - 'ts_paint', 09:06:31 INFO - 'tpaint', 09:06:31 INFO - 'sessionrestore', 09:06:31 INFO - 'sessionrestore_no_auto_restore']}, 09:06:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['a11yr', 09:06:31 INFO - 'ts_paint', 09:06:31 INFO - 'tpaint', 09:06:31 INFO - 'sessionrestore', 09:06:31 INFO - 'sessionrestore_no_auto_restore']}, 09:06:31 INFO - 'other_l64': {'tests': ['a11yr', 09:06:31 INFO - 'ts_paint', 09:06:31 INFO - 'tpaint', 09:06:31 INFO - 'sessionrestore', 09:06:31 INFO - 'sessionrestore_no_auto_restore']}, 09:06:31 INFO - 'other_nol64': {'tests': ['a11yr', 09:06:31 INFO - 'ts_paint', 09:06:31 INFO - 'tpaint', 09:06:31 INFO - 'sessionrestore', 09:06:31 INFO - 'sessionrestore_no_auto_restore']}, 09:06:31 INFO - 'svgr': {'tests': ['tsvgx', 09:06:31 INFO - 'tsvgr_opacity', 09:06:31 INFO - 'tart', 09:06:31 INFO - 'tscrollx', 09:06:31 INFO - 'cart']}, 09:06:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['tsvgx', 09:06:31 INFO - 'tsvgr_opacity', 09:06:31 INFO - 'tart', 09:06:31 INFO - 'tscrollx', 09:06:31 INFO - 'cart']}, 09:06:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'tests': ['tp5o']}, 09:06:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'talos_options': ['--e10s'], 09:06:31 INFO - 'tests': ['tp5o']}, 09:06:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'talos_options': ['--xperf_path', 09:06:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:31 INFO - 'tests': ['tp5n']}, 09:06:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:31 INFO - 'talos_options': ['--e10s', 09:06:31 INFO - '--xperf_path', 09:06:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:31 INFO - 'tests': ['tp5n']}}, 09:06:31 INFO - 'talos.zip': {'path': '', 09:06:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:31 INFO - Running post-action listener: _resource_record_post_action 09:06:31 INFO - ##### 09:06:31 INFO - ##### Running create-virtualenv step. 09:06:31 INFO - ##### 09:06:32 INFO - Running pre-action listener: _resource_record_pre_action 09:06:32 INFO - Running main action method: create_virtualenv 09:06:32 INFO - Creating virtualenv /builds/slave/test/build/venv 09:06:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:06:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:06:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:32 INFO - Using real prefix '/usr' 09:06:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:06:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:34 INFO - Installing pip.................done. 09:06:34 INFO - Return code: 0 09:06:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:06:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:06:34 INFO - Using env: {'DISPLAY': ':0', 09:06:34 INFO - 'HOME': '/home/cltbld', 09:06:34 INFO - 'LANG': 'en_US.UTF-8', 09:06:34 INFO - 'LANGUAGE': 'en_US:en', 09:06:34 INFO - 'LOGNAME': 'cltbld', 09:06:34 INFO - 'MAIL': '/var/mail/cltbld', 09:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:34 INFO - 'MOZ_NO_REMOTE': '1', 09:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:34 INFO - 'NO_EM_RESTART': '1', 09:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:34 INFO - 'PWD': '/builds/slave/test', 09:06:34 INFO - 'SHELL': '/bin/bash', 09:06:34 INFO - 'SHLVL': '1', 09:06:34 INFO - 'TERM': 'linux', 09:06:34 INFO - 'TMOUT': '86400', 09:06:34 INFO - 'USER': 'cltbld', 09:06:34 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:34 INFO - '_': '/tools/buildbot/bin/python'} 09:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:34 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 09:06:34 INFO - Cleaning up... 09:06:34 INFO - Return code: 0 09:06:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:06:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:06:34 INFO - Using env: {'DISPLAY': ':0', 09:06:34 INFO - 'HOME': '/home/cltbld', 09:06:34 INFO - 'LANG': 'en_US.UTF-8', 09:06:34 INFO - 'LANGUAGE': 'en_US:en', 09:06:34 INFO - 'LOGNAME': 'cltbld', 09:06:34 INFO - 'MAIL': '/var/mail/cltbld', 09:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:34 INFO - 'MOZ_NO_REMOTE': '1', 09:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:34 INFO - 'NO_EM_RESTART': '1', 09:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:34 INFO - 'PWD': '/builds/slave/test', 09:06:34 INFO - 'SHELL': '/bin/bash', 09:06:34 INFO - 'SHLVL': '1', 09:06:34 INFO - 'TERM': 'linux', 09:06:34 INFO - 'TMOUT': '86400', 09:06:34 INFO - 'USER': 'cltbld', 09:06:34 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:34 INFO - '_': '/tools/buildbot/bin/python'} 09:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:35 INFO - Downloading/unpacking psutil>=0.7.1 09:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:36 INFO - Installing collected packages: psutil 09:06:36 INFO - Running setup.py install for psutil 09:06:37 INFO - building 'psutil._psutil_linux' extension 09:06:37 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 09:06:37 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 09:06:37 INFO - building 'psutil._psutil_posix' extension 09:06:37 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 09:06:37 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 09:06:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:37 INFO - Successfully installed psutil 09:06:37 INFO - Cleaning up... 09:06:37 INFO - Return code: 0 09:06:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:06:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:06:37 INFO - Using env: {'DISPLAY': ':0', 09:06:37 INFO - 'HOME': '/home/cltbld', 09:06:37 INFO - 'LANG': 'en_US.UTF-8', 09:06:37 INFO - 'LANGUAGE': 'en_US:en', 09:06:37 INFO - 'LOGNAME': 'cltbld', 09:06:37 INFO - 'MAIL': '/var/mail/cltbld', 09:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:37 INFO - 'MOZ_NO_REMOTE': '1', 09:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:37 INFO - 'NO_EM_RESTART': '1', 09:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:37 INFO - 'PWD': '/builds/slave/test', 09:06:37 INFO - 'SHELL': '/bin/bash', 09:06:37 INFO - 'SHLVL': '1', 09:06:37 INFO - 'TERM': 'linux', 09:06:37 INFO - 'TMOUT': '86400', 09:06:37 INFO - 'USER': 'cltbld', 09:06:37 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:37 INFO - '_': '/tools/buildbot/bin/python'} 09:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:06:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:06:39 INFO - Installing collected packages: mozsystemmonitor 09:06:39 INFO - Running setup.py install for mozsystemmonitor 09:06:39 INFO - Successfully installed mozsystemmonitor 09:06:39 INFO - Cleaning up... 09:06:39 INFO - Return code: 0 09:06:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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', '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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09: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 blobuploader==1.2.4 09:06:39 INFO - Using env: {'DISPLAY': ':0', 09:06:39 INFO - 'HOME': '/home/cltbld', 09:06:39 INFO - 'LANG': 'en_US.UTF-8', 09:06:39 INFO - 'LANGUAGE': 'en_US:en', 09:06:39 INFO - 'LOGNAME': 'cltbld', 09:06:39 INFO - 'MAIL': '/var/mail/cltbld', 09:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:39 INFO - 'MOZ_NO_REMOTE': '1', 09:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:39 INFO - 'NO_EM_RESTART': '1', 09:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:39 INFO - 'PWD': '/builds/slave/test', 09:06:39 INFO - 'SHELL': '/bin/bash', 09:06:39 INFO - 'SHLVL': '1', 09:06:39 INFO - 'TERM': 'linux', 09:06:39 INFO - 'TMOUT': '86400', 09:06:39 INFO - 'USER': 'cltbld', 09:06:39 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:39 INFO - '_': '/tools/buildbot/bin/python'} 09:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:39 INFO - Downloading/unpacking blobuploader==1.2.4 09: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 09: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 09: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 09: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 09: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 09: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 09:06:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:06:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:06:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09: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 09: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 09: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 09: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 09: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 09: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 09:06:42 INFO - Downloading docopt-0.6.1.tar.gz 09:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:06:42 INFO - Installing collected packages: blobuploader, requests, docopt 09:06:42 INFO - Running setup.py install for blobuploader 09:06:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:06:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:06:42 INFO - Running setup.py install for requests 09:06:43 INFO - Running setup.py install for docopt 09:06:43 INFO - Successfully installed blobuploader requests docopt 09:06:43 INFO - Cleaning up... 09:06:43 INFO - Return code: 0 09:06:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:43 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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:43 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 09:06:43 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 09:06:43 INFO - Using env: {'DISPLAY': ':0', 09:06:43 INFO - 'HOME': '/home/cltbld', 09:06:43 INFO - 'LANG': 'en_US.UTF-8', 09:06:43 INFO - 'LANGUAGE': 'en_US:en', 09:06:43 INFO - 'LOGNAME': 'cltbld', 09:06:43 INFO - 'MAIL': '/var/mail/cltbld', 09:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:43 INFO - 'MOZ_NO_REMOTE': '1', 09:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:43 INFO - 'NO_EM_RESTART': '1', 09:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:43 INFO - 'PWD': '/builds/slave/test', 09:06:43 INFO - 'SHELL': '/bin/bash', 09:06:43 INFO - 'SHLVL': '1', 09:06:43 INFO - 'TERM': 'linux', 09:06:43 INFO - 'TMOUT': '86400', 09:06:43 INFO - 'USER': 'cltbld', 09:06:43 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:43 INFO - '_': '/tools/buildbot/bin/python'} 09:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:43 INFO - Running setup.py (path:/tmp/pip-oNrvbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:43 INFO - Running setup.py (path:/tmp/pip-eQL8gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:43 INFO - Running setup.py (path:/tmp/pip-YchiNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:43 INFO - Running setup.py (path:/tmp/pip-tt8mmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:43 INFO - Running setup.py (path:/tmp/pip-xs9hm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:43 INFO - Running setup.py (path:/tmp/pip-qUTGJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:43 INFO - Running setup.py (path:/tmp/pip-biJXc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:43 INFO - Running setup.py (path:/tmp/pip-ysNbqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:44 INFO - Running setup.py (path:/tmp/pip-_KhTEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:44 INFO - Running setup.py (path:/tmp/pip-j4Iphu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:44 INFO - Running setup.py (path:/tmp/pip-YGQaP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:44 INFO - Running setup.py (path:/tmp/pip-l5R0bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:44 INFO - Running setup.py (path:/tmp/pip-SCFfTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:44 INFO - Running setup.py (path:/tmp/pip-ToEwkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:44 INFO - Running setup.py (path:/tmp/pip-FQw8K9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:44 INFO - Running setup.py (path:/tmp/pip-VejHRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:44 INFO - Running setup.py (path:/tmp/pip-e9C64k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:06:44 INFO - Running setup.py install for manifestparser 09:06:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:06:44 INFO - Running setup.py install for mozcrash 09:06:44 INFO - Running setup.py install for mozdebug 09:06:44 INFO - Running setup.py install for mozdevice 09:06:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Running setup.py install for mozfile 09:06:45 INFO - Running setup.py install for mozhttpd 09:06:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Running setup.py install for mozinfo 09:06:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Running setup.py install for mozInstall 09:06:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Running setup.py install for mozleak 09:06:45 INFO - Running setup.py install for mozlog 09:06:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Running setup.py install for moznetwork 09:06:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:06:45 INFO - Running setup.py install for mozprocess 09:06:45 INFO - Running setup.py install for mozprofile 09:06:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:06:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:06:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:06:46 INFO - Running setup.py install for mozrunner 09:06:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:06:46 INFO - Running setup.py install for mozscreenshot 09:06:46 INFO - Running setup.py install for moztest 09:06:46 INFO - Running setup.py install for mozversion 09:06:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:06:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:46 INFO - Cleaning up... 09:06:46 INFO - Return code: 0 09:06:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:46 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')]} 09:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:46 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')]} 09:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:46 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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:46 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 09:06:46 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 09:06:46 INFO - Using env: {'DISPLAY': ':0', 09:06:46 INFO - 'HOME': '/home/cltbld', 09:06:46 INFO - 'LANG': 'en_US.UTF-8', 09:06:46 INFO - 'LANGUAGE': 'en_US:en', 09:06:46 INFO - 'LOGNAME': 'cltbld', 09:06:46 INFO - 'MAIL': '/var/mail/cltbld', 09:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:46 INFO - 'MOZ_NO_REMOTE': '1', 09:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:46 INFO - 'NO_EM_RESTART': '1', 09:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:46 INFO - 'PWD': '/builds/slave/test', 09:06:46 INFO - 'SHELL': '/bin/bash', 09:06:46 INFO - 'SHLVL': '1', 09:06:46 INFO - 'TERM': 'linux', 09:06:46 INFO - 'TMOUT': '86400', 09:06:46 INFO - 'USER': 'cltbld', 09:06:46 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:46 INFO - '_': '/tools/buildbot/bin/python'} 09:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:46 INFO - Running setup.py (path:/tmp/pip-KdXPrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:46 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)) 09:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:46 INFO - Running setup.py (path:/tmp/pip-JA_LkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 09:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:46 INFO - Running setup.py (path:/tmp/pip-wFxNPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:46 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)) 09:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:46 INFO - Running setup.py (path:/tmp/pip-4Km3xB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:46 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)) 09:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:46 INFO - Running setup.py (path:/tmp/pip-RBgVhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:47 INFO - Running setup.py (path:/tmp/pip-cq79rU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:47 INFO - Running setup.py (path:/tmp/pip-xxBgTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:47 INFO - Running setup.py (path:/tmp/pip-F_mU0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:47 INFO - Running setup.py (path:/tmp/pip-hBLoei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:47 INFO - Running setup.py (path:/tmp/pip-4UMPei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:47 INFO - Running setup.py (path:/tmp/pip-t7glei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:47 INFO - Running setup.py (path:/tmp/pip-k7GHBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:47 INFO - Running setup.py (path:/tmp/pip-KqBPmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:47 INFO - Running setup.py (path:/tmp/pip-UVovjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:47 INFO - Running setup.py (path:/tmp/pip-1GBXA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:47 INFO - Running setup.py (path:/tmp/pip-NdXUes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:47 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)) 09:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:47 INFO - Running setup.py (path:/tmp/pip-FA6YIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:47 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)) 09:06:47 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:47 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:47 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)) 09:06:47 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)) 09:06:47 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)) 09:06:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06:47 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 09:06:47 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 09:06:47 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 09:06:47 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 09:06:47 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 09:06:47 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 09:06:49 INFO - Downloading blessings-1.6.tar.gz 09:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:49 INFO - Installing collected packages: blessings 09:06:49 INFO - Running setup.py install for blessings 09:06:49 INFO - Successfully installed blessings 09:06:49 INFO - Cleaning up... 09:06:49 INFO - Return code: 0 09:06:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:06:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:50 INFO - Reading from file tmpfile_stdout 09:06:50 INFO - Current package versions: 09:06:50 INFO - argparse == 1.2.1 09:06:50 INFO - blessings == 1.6 09:06:50 INFO - blobuploader == 1.2.4 09:06:50 INFO - docopt == 0.6.1 09:06:50 INFO - manifestparser == 1.1 09:06:50 INFO - mozInstall == 1.12 09:06:50 INFO - mozcrash == 0.16 09:06:50 INFO - mozdebug == 0.1 09:06:50 INFO - mozdevice == 0.48 09:06:50 INFO - mozfile == 1.2 09:06:50 INFO - mozhttpd == 0.7 09:06:50 INFO - mozinfo == 0.9 09:06:50 INFO - mozleak == 0.1 09:06:50 INFO - mozlog == 3.1 09:06:50 INFO - moznetwork == 0.27 09:06:50 INFO - mozprocess == 0.22 09:06:50 INFO - mozprofile == 0.28 09:06:50 INFO - mozrunner == 6.11 09:06:50 INFO - mozscreenshot == 0.1 09:06:50 INFO - mozsystemmonitor == 0.0 09:06:50 INFO - moztest == 0.7 09:06:50 INFO - mozversion == 1.4 09:06:50 INFO - psutil == 3.1.1 09:06:50 INFO - requests == 1.2.3 09:06:50 INFO - wsgiref == 0.1.2 09:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09: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')]} 09:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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/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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:50 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 09:06:50 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 09:06:50 INFO - Using env: {'DISPLAY': ':0', 09:06:50 INFO - 'HOME': '/home/cltbld', 09:06:50 INFO - 'LANG': 'en_US.UTF-8', 09:06:50 INFO - 'LANGUAGE': 'en_US:en', 09:06:50 INFO - 'LOGNAME': 'cltbld', 09:06:50 INFO - 'MAIL': '/var/mail/cltbld', 09:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:50 INFO - 'MOZ_NO_REMOTE': '1', 09:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:50 INFO - 'NO_EM_RESTART': '1', 09:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:50 INFO - 'PWD': '/builds/slave/test', 09:06:50 INFO - 'SHELL': '/bin/bash', 09:06:50 INFO - 'SHLVL': '1', 09:06:50 INFO - 'TERM': 'linux', 09:06:50 INFO - 'TMOUT': '86400', 09:06:50 INFO - 'USER': 'cltbld', 09:06:50 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:50 INFO - '_': '/tools/buildbot/bin/python'} 09:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 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)) 09:06:50 INFO - Cleaning up... 09:06:50 INFO - Return code: 0 09:06:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09: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')]} 09:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:50 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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:50 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 09:06:50 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 09:06:50 INFO - Using env: {'DISPLAY': ':0', 09:06:50 INFO - 'HOME': '/home/cltbld', 09:06:50 INFO - 'LANG': 'en_US.UTF-8', 09:06:50 INFO - 'LANGUAGE': 'en_US:en', 09:06:50 INFO - 'LOGNAME': 'cltbld', 09:06:50 INFO - 'MAIL': '/var/mail/cltbld', 09:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:50 INFO - 'MOZ_NO_REMOTE': '1', 09:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:50 INFO - 'NO_EM_RESTART': '1', 09:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:50 INFO - 'PWD': '/builds/slave/test', 09:06:50 INFO - 'SHELL': '/bin/bash', 09:06:50 INFO - 'SHLVL': '1', 09:06:50 INFO - 'TERM': 'linux', 09:06:50 INFO - 'TMOUT': '86400', 09:06:50 INFO - 'USER': 'cltbld', 09:06:50 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:50 INFO - '_': '/tools/buildbot/bin/python'} 09:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:50 INFO - Downloading/unpacking jsonschema 09:06:50 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 09:06:50 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 09:06:50 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 09:06:50 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 09:06:50 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 09:06:50 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 09:06:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:06:52 INFO - Downloading/unpacking functools32 (from jsonschema) 09: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 09: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 09: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 09: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 09: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 09: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 09:06:52 INFO - Downloading functools32-3.2.3-2.tar.gz 09:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:06:52 INFO - warning: no files found matching '*.txt' 09:06:52 INFO - no previously-included directories found matching 'build' 09:06:52 INFO - no previously-included directories found matching 'dist' 09:06:52 INFO - no previously-included directories found matching '.git*' 09:06:52 INFO - Installing collected packages: jsonschema, functools32 09:06:52 INFO - Running setup.py install for functools32 09:06:52 INFO - warning: no files found matching '*.txt' 09:06:52 INFO - no previously-included directories found matching 'build' 09:06:52 INFO - no previously-included directories found matching 'dist' 09:06:52 INFO - no previously-included directories found matching '.git*' 09:06:52 INFO - Successfully installed jsonschema functools32 09:06:52 INFO - Cleaning up... 09:06:52 INFO - Return code: 0 09:06:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:06:52 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')]} 09:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:52 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')]} 09:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:52 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 0x7f99a5884f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2317e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', '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 09:06:52 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 09:06:52 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 09:06:52 INFO - Using env: {'DISPLAY': ':0', 09:06:52 INFO - 'HOME': '/home/cltbld', 09:06:52 INFO - 'LANG': 'en_US.UTF-8', 09:06:52 INFO - 'LANGUAGE': 'en_US:en', 09:06:52 INFO - 'LOGNAME': 'cltbld', 09:06:52 INFO - 'MAIL': '/var/mail/cltbld', 09:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:52 INFO - 'MOZ_NO_REMOTE': '1', 09:06:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:52 INFO - 'NO_EM_RESTART': '1', 09:06:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:52 INFO - 'PWD': '/builds/slave/test', 09:06:52 INFO - 'SHELL': '/bin/bash', 09:06:52 INFO - 'SHLVL': '1', 09:06:52 INFO - 'TERM': 'linux', 09:06:52 INFO - 'TMOUT': '86400', 09:06:52 INFO - 'USER': 'cltbld', 09:06:52 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:52 INFO - '_': '/tools/buildbot/bin/python'} 09:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:53 INFO - Downloading/unpacking flake8 09:06:53 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 09:06:53 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 09:06:53 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 09:06:53 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 09:06:53 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 09:06:53 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 09:06:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:06:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09: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 09: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 09: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 09: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 09: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 09: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 09:06:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:06:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 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 09:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:06:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:06:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:06:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:06:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:06:55 INFO - no previously-included directories found matching 'docs/_build' 09:06:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:06:55 INFO - Running setup.py install for pep8 09:06:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:06:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:06:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:06:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:06:55 INFO - no previously-included directories found matching 'docs/_build' 09:06:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:06:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:06:55 INFO - Cleaning up... 09:06:55 INFO - Return code: 0 09:06:55 INFO - Running post-action listener: _resource_record_post_action 09:06:55 INFO - Running post-action listener: _start_resource_monitoring 09:06:55 INFO - Starting resource monitoring. 09:06:55 INFO - ##### 09:06:55 INFO - ##### Running install step. 09:06:55 INFO - ##### 09:06:55 INFO - Running pre-action listener: _resource_record_pre_action 09:06:55 INFO - Running main action method: install 09:06:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:56 INFO - Reading from file tmpfile_stdout 09:06:56 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:06:56 INFO - Reading from file tmpfile_stdout 09:06:56 INFO - Output received: 09:06:56 INFO - Usage: mozinstall [options] installer 09:06:56 INFO - Options: 09:06:56 INFO - -h, --help show this help message and exit 09:06:56 INFO - -d DEST, --destination=DEST 09:06:56 INFO - Directory to install application into. [default: 09:06:56 INFO - "/builds/slave/test"] 09:06:56 INFO - --app=APP Application being installed. [default: firefox] 09:06:56 INFO - mkdir: /builds/slave/test/build/application 09:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:07:08 INFO - Reading from file tmpfile_stdout 09:07:08 INFO - Output received: 09:07:08 INFO - /builds/slave/test/build/application/firefox/firefox 09:07:08 INFO - Running post-action listener: _resource_record_post_action 09:07:08 INFO - ##### 09:07:08 INFO - ##### Running run-tests step. 09:07:08 INFO - ##### 09:07:08 INFO - Running pre-action listener: _resource_record_pre_action 09:07:08 INFO - Running main action method: run_tests 09:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:07:08 INFO - Python 2.7.3 09:07:08 INFO - Return code: 0 09:07:08 INFO - grabbing minidump binary from tooltool 09:07:08 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')]} 09:07:08 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa430>, '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 0x2315f30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23163c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:07:08 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 09:07:08 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 09:07:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:08 INFO - Return code: 0 09:07:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:07:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:07:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:07:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:07:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:07:09 INFO - Return code: 0 09:07:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Date-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 09:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Date-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 09:07:09 INFO - Using env: {'DISPLAY': ':0', 09:07:09 INFO - 'HOME': '/home/cltbld', 09:07:09 INFO - 'LANG': 'en_US.UTF-8', 09:07:09 INFO - 'LANGUAGE': 'en_US:en', 09:07:09 INFO - 'LOGNAME': 'cltbld', 09:07:09 INFO - 'MAIL': '/var/mail/cltbld', 09:07:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:07:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:09 INFO - 'MOZ_NO_REMOTE': '1', 09:07:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:09 INFO - 'NO_EM_RESTART': '1', 09:07:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:09 INFO - 'PWD': '/builds/slave/test', 09:07:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:09 INFO - 'SHELL': '/bin/bash', 09:07:09 INFO - 'SHLVL': '1', 09:07:09 INFO - 'TERM': 'linux', 09:07:09 INFO - 'TMOUT': '86400', 09:07:09 INFO - 'USER': 'cltbld', 09:07:09 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688', 09:07:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:09 INFO - '_': '/tools/buildbot/bin/python'} 09:07:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Date-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 09:07:09 INFO - mozversion application_buildid: 20160302200635 09:07:09 INFO - mozversion application_changeset: 07e13e514846833c01f65812d04b9c6a2f4b9f84 09:07:09 INFO - mozversion application_display_name: Firefox 09:07:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:07:09 INFO - mozversion application_name: Firefox 09:07:09 INFO - mozversion application_remotingname: firefox 09:07:09 INFO - mozversion application_repository: https://hg.mozilla.org/projects/date 09:07:09 INFO - mozversion application_vendor: Mozilla 09:07:09 INFO - mozversion application_version: 46.0 09:07:09 INFO - mozversion platform_buildid: 20160302200635 09:07:09 INFO - mozversion platform_changeset: 07e13e514846833c01f65812d04b9c6a2f4b9f84 09:07:09 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/date 09:07:09 INFO - mozversion platform_version: 46.0 09:07:09 INFO - using testdate: 1457024829 09:07:09 INFO - actual date: 1457024829 09:07:10 INFO - starting webserver on 'localhost:47614' 09:07:10 INFO - SUITE-START | Running 3 tests 09:07:10 INFO - TEST-START | dromaeo_css 09:07:10 INFO - operating with platform_type : linux_ 09:07:10 INFO - Initialising browser for dromaeo_css test... 09:07:10 INFO - TEST-INFO | started process 16651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGKlk8U/profile http://localhost:47614/getInfo.html) 09:07:14 INFO - PROCESS | 16651 | __metrics Screen width/height:1600/1200 09:07:14 INFO - PROCESS | 16651 | colorDepth:24 09:07:14 INFO - PROCESS | 16651 | Browser inner width/height: 1024/697 09:07:14 INFO - PROCESS | 16651 | __metrics 09:07:14 INFO - PROCESS | 16651 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:07:15 INFO - TEST-INFO | 16651: exit 0 09:07:15 INFO - Browser initialized. 09:07:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:07:15 INFO - TEST-INFO | started process 16740 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGKlk8U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:26 INFO - PROCESS | 16740 | RSS: Main: 136126464 09:07:26 INFO - PROCESS | 16740 | 09:08:18 INFO - PROCESS | 16740 | 1457024898489 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] 09:10:25 INFO - PROCESS | 16740 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47614/tests/dromaeo/cssquery-ext.html) 09:10:26 INFO - PROCESS | 16740 | RSS: Main: 222777344 09:10:26 INFO - PROCESS | 16740 | 09:13:28 INFO - PROCESS | 16740 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-ext.html (next: http://localhost:47614/tests/dromaeo/cssquery-jquery.html) 09:13:28 INFO - PROCESS | 16740 | RSS: Main: 212893696 09:13:28 INFO - PROCESS | 16740 | 09:16:30 INFO - PROCESS | 16740 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47614/tests/dromaeo/cssquery-mootools.html) 09:16:31 INFO - PROCESS | 16740 | RSS: Main: 227258368 09:16:31 INFO - PROCESS | 16740 | 09:19:32 INFO - PROCESS | 16740 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47614/tests/dromaeo/cssquery-prototype.html) 09:19:33 INFO - PROCESS | 16740 | RSS: Main: 200327168 09:19:33 INFO - PROCESS | 16740 | 09:22:35 INFO - PROCESS | 16740 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47614/tests/dromaeo/cssquery-yui.html) 09:22:36 INFO - PROCESS | 16740 | RSS: Main: 198901760 09:22:36 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | Cycle 1(1): loaded http://localhost:47614/tests/dromaeo/cssquery-yui.html (next: http://localhost:47614/tests/dromaeo/cssquery-dojo.html) 09:25:38 INFO - PROCESS | 16740 | RSS: Main: 244957184 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | __start_tp_report 09:25:38 INFO - PROCESS | 16740 | _x_x_mozilla_page_load 09:25:38 INFO - PROCESS | 16740 | _x_x_mozilla_page_load_details 09:25:38 INFO - PROCESS | 16740 | |i|pagename|runs| 09:25:38 INFO - PROCESS | 16740 | |0;dojo.html;3223.8388080595973;3249.2689144942406;3273.5744353234077;3276.7378609711227;3297.736181105511;5180.430152683211;5185.948140518593;5186.247994040862;5206.7656955437005;5212.687238765677;5211.5309622134;5237.240600112981;5237.685738855671;5244.777953747232;5276.525112739852;4691.530846915315;4789.2097803862325;4828.468868424462;4828.56542911138;4877.585405240558;4746.359241502405;4772.331873537708;4781.163296423129;4783.7129772213775;4796.544328288807;4737.692050016738;4745.810167593309;4804.20730579454;4836.709797412149;4845.685030473008;4907.730074845882;4980.377452818398;4986.252062190664;4994.575461085812;4995.225740010304;4754.881127971794;4756.191447453941;4785.880352991168;4789.49710280419;4832.251001094836;4936.136176169156;4954.033963377143;5041.899162016739;5105.5149760772665;5116.2069879168785;5667.546596272329;5713.400092990218;5724.198612194296;5724.484796368347;5726.513246374063;5740.311162660466;5750.539956803445;5752.9712351437975;5756.769729210827;5765.769369225268;5288.629795914288;5337.5196232338885;5362.2224777407055;5427.565794736412;5451.8364449066985;5857.912131318034;5883.529317654577;5896.6756828374855;5897;5897.262842144732;5495.340559132929;5625.8593535161945;5631.2960879890015;5631.958087753778;5659.292588426447;5635.239242702265;5714.2571465710025;5773.797917072882;5780.450857168562;5811.4479124483105;5535.5848311376;5544.085225937756;5544.417836127229;5548.278723765965;5551.195076713854;5520.309961254843;5529.087700529448;5529.419410961871;5603.7478313475995;5691.203231547586;4393.648508119407;4410.051838854598;4432.202203603318;4448.377227188196;4453.287474004143;3960.405939109157;4005;4016.216837716617;4017.7589344639414;4033.3748269018347;5331.573474122139;5414.458554144633;5441.183822426588;5465.289512363367;5528.502434780889;5732.57005724564;5732.742026608813;5750.7987220447085;5762.625429347079;5763.769449221984;5425.430329815392;5437.184422336602;5486.204500347507;5487.588430867621;5488.094464413327;4790.856274311776;4861.975690121527;4975.0547395994645;5006.749662516932;5044.2937988681615;5884.382139875252;5911.674857882857;5921;5928.822135335947;5930.021546444788;5712.343080545707;5747.597668163188;5748.655080695172;5750.223719797774;5751.166080918327;5754.395788442153;5812.912806307824;5840.1531777892815;5881.735321910439;5908.586398952204;5588.385277619377;5636.070048442125;5641.26663533738;5643.858903527444;5647.983362994536;5485.807996720256;5684.346300175533;5693.231413759089;5724.79963201286;5727.513161381329;5516.420775818481;5538.0585300499815;5589.077802162597;5606.85982850432;5623.943760562342;5472.398036215934;5476.644018138808;5478.260434841245;5481.314835645544;5499.642523235977;5380.811671591476;5408.323959505062;5438.592105592175;5443.455654434525;5531.640443371168;5600.831975040755;5685.260916080883;5706.058500347563;5714.114312281603;5747.453991870766;5458.454154584669;5459.918101228564;5462.644928079662;5463.808766693147;5473.562115030708 09:25:38 INFO - PROCESS | 16740 | |1;ext.html;545.5390195284984;548.574854487772;556.0713608274182;572.64209868832;587.4918195760669;31174.376512469764;39617.60382396175;39984;40030.59969400305;40053.19893602126;43056.3541546877;43807.3428898566;43912.560874391325;44003.77998110013;44069.33895991555;8644.740657780276;8823.691170809023;8890.022097569263;8898.688545900895;8902;8463.703770368038;8632.870506942401;8645.308375329958;8646.740597782045;8862.158094980967;8852.822943541125;8899.065598112202;8935.55322233889;8939.73180804577;8942.374033817636;8822.470651760916;8883.600237989305;8887.866681999774;8895.332850036208;8898.688545900895;8786.64853405863;8932.419392739452;8942.64229430822;8954.05982371848;8962.327825413055;43827.561724382664;44181.337279940824;44271.557284427065;44274.114517709466;44377.11245775098;3813.637704418076;3835.271298453285;4061.3501839705505;4267.935980960288;4387.62705170061;33031.45496051289;33305;33527.83236083804;33842.83078584616;33967;4118.897027574335;4180.770057646859;4200.70595058346;4204.285271503852;4206.3690446432975;292073;292270;292580;293008;293306;61180.38819611747;62314.68842655758;64803;65416.34583654198;68140;59431.702841485516;59439.216823495364;59654;59679.403205967385;59876;18704.25182992692;18934.526636833914;18989.335373262147;18996.43010709681;19040.52398690016;12800.295983720986;12807.551735689207;12831.422585983653;12837.743245135045;12841.935790321175;22135;22307.665385018914;22488.775112248994;22504.774952250267;22511.099556017904;7781.105172905189;7907.130215676271;8021.197880212048;8040.638171282307;8102.7569172924905;945.8817647794026;969.3699095587783;1061.6072053340313;1077.8814330423647;1150.775598758251;30392;30479.39041219163;30680.251454623954;30681.69318306833;31215.375692486024;1125.054953838763;1143.3082984794246;1149.6321177223208;1229.4037391865118;1283.4801905228398;13935.512257070954;13997.3701183447;15895.364185432452;16237;16517.752233716503;269982;272540;272571;272731;272895;53872;54089.72955135278;54105.45894541083;54121;54140.45859541433;742.3578604507062;1338.625184948216;1391.311300906058;1440.2150827798666;1550.9457168999256;27671.446571068063;29325.56011659784;29530.409391812474;29743;30202.24494387658;43369.34945975876;43449;43456.782716086214;43468.782656086514;44919.77540112279;247049;247208;247418;248732;248857;33972.83013584916;34614.30771384609;34618.48072278868;34762.478562822085;34929.30141397208;47638.28542571933;48511.75744121257;48572.27141592808;48920.5107948916;49380.50619493759;40333.596664032986;40596.79701601473;40815.59184408118;41381.1723765517;41823.16353672848;39912.401313980896;41026.79486602548;41047.65608639732;41220.79389603033;41698;39890.40164397594;40403.797981009906;40689.38965915573;40878.1824363517;41190.38214426846;31078.22304442407;32525.34949301048;33586.83206583952;34562;35847.46228806518;27697.307567310978;28565.295527157214;28910.760433276417;29251.7074829249;29547.40905181927 09:25:38 INFO - PROCESS | 16740 | |2;jquery.html;379.96770274526665;392.91159489114955;394.70199999000766;395.55895176877783;397.4257198348386;14113.223772692498;14693.412263509446;16528;18815.71776423355;18862.717059244103;17140.657186856257;17313.30746770131;17360.739588906177;18309.725354119666;18333.90833045833;6097.634141951497;6305;6311.968440157816;6337.049442583603;6338.651374174418;4607.838725644603;4735.408073990751;4740.786664600101;6203.720832562523;6803.0815839861725;5359.892802143955;5557.805476808313;7012.754553590626;7047.048648432447;7054.611996340199;5542.86142846428;5561.77752889884;5562.833115006556;5573.247611572427;5582.330120385539;5279.656822306538;5486.396496385434;5489.368722596912;5490.478404551562;5492.862678433031;13789.172649641054;15363.462278820187;20327.49181270465;21095.68356474654;21272.680909786366;2210.502636906699;2228.621134407155;2235.2065016918923;2293.415179129311;2354.9411264718347;10258.692239232834;11180.496877640526;12233.816492752616;13624.114432561852;13818.032737708365;1916.2430839818333;1936.1577713694587;1946.8247857692734;2023.1401654296865;2047.9897600512052;60300;60596;62074.689626552026;62277.37722622806;62905.37094629087;10987.725306867233;11061.170412218955;11061.281016733887;11061.834072488919;11142.888571114347;14689.19209443491;14696.632584185481;14774.335154917839;14823.258837058103;14841;8380.622871970776;8380.706675266336;8407.243348098578;8415.45299555527;8436.493810371398;8066.596670166468;8094.635741391651;8099.392545559107;8100.635471403801;8115.229053239933;4438.733675979452;4640.65195110368;4789.090072886141;4889.339939108175;4899.681520701143;1871.8034606366407;1940.194819150065;1964.65618516759;1969.9113539890739;1976.7924367941446;956.3257903178243;1003.0471052500009;1062.8830828608848;1065.4512925843196;1101.498818037791;15744.92127539355;16177.757333640006;16702;17302.567435813955;17835;954.8472244440856;965.7440778193784;978.5938835383379;983.3509883476871;1083.1767856429012;9046.547672616474;9353.672621458216;9460.905390946002;10043.598256069676;10737.51681174349;632166;632944;646968;650619;667970;47268.29097563539;68243;75567.24432755602;75640.62179689066;77641;1192.5289510643247;1196.7187710887933;1202.8436303280519;1216.707990082374;1223.9755204895853;15041.849581504483;15816.841831581536;16050.438234661731;16286.918565407097;16335.428260010842;33954.66045339515;34063.4890476648;34818.82590587031;35141.64858351384;35820.820895895355;41755;41774;42627.360589590564;42772.358414624425;43126.35310470283;7873.291403773688;7873.5275883447175;8062.193780622126;8108.39187060961;8181.877271840808;8585.82828343442;11018.944905275743;11039.668809935714;11327.660170194908;11610.419479026184;10081.495925203857;10134.949325253327;10314.638987635397;10347.94826025865;10611.2041596879;8472.279856212044;9969.800603988024;10480.58077676915;10504.894951050392;10744.194185435967;10357.430341330997;10537.841932371173;10718.517666704862;10780.838287425851;10943.23397362209;7791.571463569559;8051.718189863327;8084.514929104273;8120.269175774209;8134.63394147253;9575.329726919048;10520.473976301002;10534.209934255116;10679.46602669848;10771.569137234736 09:25:38 INFO - PROCESS | 16740 | |3;mootools.html;1404.5294826233212;1533.6625942292694;1574.8110226772783;1775.440736168107;1877.9624407511858;7401.0748656417945;7930.40521960853;8097.716579919704;8111.148329425399;8118.91881081189;7915.564643944581;8051.838963220732;8097.433179677425;8098.595070246493;8106.067802202734;2440.0605766779827;2472.307753828931;2473.1714875516723;2474.4679893822827;2475.5296493666237;2345.777151170636;2370.111208296889;2457.066314800373;2459.9016039358403;2461.187808023357;2373.027058905849;2396.161343529768;2406.0255596483366;2417.033186725306;2432.84186527876;2367.1714899785024;2388.677528533643;2393.533261014103;2395.113807891074;2435.9512809743705;2328.4178955261186;2330.7086614173227;2332.731735850382;2338.181636427254;2355.328731311585;8279.793005174859;8308.750737477885;8321.66713331466;8322.83354332916;8325.25072743456;5072.188449848007;5183.041137389558;5189.065968125736;5195.064888320101;5200.843974680765;3324.734021278316;3349.246419555605;3412.709919656832;3424.298018906118;3450.4824276358495;1140.954361825526;1197.1619866093681;1201.237214368882;1228.748106638137;1243.0801207106767;11790.646280611596;11791.823122653168;11802.881971180264;11818.822717659243;11830;4609.377734005866;4644.86065418038;4649.349091127245;4673.929891051637;4745.620350371962;9893.65372211969;9903.80192396148;9907.356021858555;9913.45475998827;9933.056359645821;5071.467495912876;5105.106606343875;5114.71869047206;5115.846524604268;5116.079105760999;4165.042040330741;4209.158168366339;4599.333096701027;4649.04694537619;4706.717596944194;3580.7672501287334;3593.5328407307406;3639.6542328478754;3643.890683279506;3679.5952445231005;1207.824865394513;1257.2268055146203;1261.9369031548385;1275.0755702116078;1279.6544932868073;1429.8999070064995;1433.1902475101742;1433.4982756035304;1443.8556144385686;1449.1087980891887;7821.843563128706;7844.254795794391;7876.448648594658;7883.093444253869;7887.723929662434;541.5667466027242;550.3038656100036;551.503646717957;558.904986152355;559.4377650461261;6308.337624549448;6332.271788744261;6336.6831658416895;6349.6507692077375;6370.980643097082;9871.210303175729;10024.699259022242;10130.594776208869;10131.949340253252;10161.949190254001;8974.596143173572;9956.352837065568;10086.49567521621;10103.19174466041;10138.645147419806;7792.337651299589;7805.960970195226;7852.882206766903;7875.133735289113;7972.123066462685;7560.773176804704;7669.041369828771;7842.098158711593;7873.33076688465;7907.367410607253;8631.093735157969;8866.556672166495;8921.553922303729;8937.508437035765;9043.23132533742;9029.954850225708;9266.768330791785;9303.953480232556;9459.574319155792;9467.242620590474;6445.355464453704;6561.212654481302;6904.930950690429;7175.712971481083;7305.232950540117;7537.095548534211;7562.697492100255;7585.658645360862;7608.277213664693;7610.961945190239;7278.126624805057;7376.225496322809;7385.076865391826;7427.294407031323;7579.166291707797;7212.819679507845;7226.602536860523;7326.853462930818;7415.480916335805;7508.887366689395;7591.58246296459;7668.463207575416;7683.577403242875;7686.46194766361;7692.7692169235015;7079.646017698991;7137.179224389054;7187.389071928945;7189.388901943394;7192.604406757679;7068.081149450539;7083.1854336749875;7124.893126603001;7140.607266600179;7143.678534465857 09:25:38 INFO - PROCESS | 16740 | |4;prototype.html;348.82035751587927;358.0600922578232;359.13627725320606;359.60084306419867;360.6069384371035;5990.520758339328;6032.215811944447;6034.547408944325;6039.72821223045;6087.147799308101;5696.857578560538;5810.5932584719085;5837.8832423351505;5916.201312822767;5979.1330257112695;4649.418822647169;4665.2768820832825;4668.043051174501;4672.135654903854;4682.133805246023;4462.620677242438;4685.742284174369;4687.273472611751;4691.741954192518;4696.741679207642;4494.393256910308;4499.437570303712;4552.68131230814;4557.293619488984;4560.065186636748;4581.977090114545;4603.907921841578;4608.055348653518;4613.930791038137;4615.769211539427;4099.508059032905;4106.260873042851;4107.527634322061;4107.938380924288;4111.403846442249;5564.221009058735;5697.544196464314;5708.286464191976;5708.7145642718115;5713.314402271712;5387.730613469311;5389.8113566025;5401.837944861661;5405.054115529767;5406.270153529263;6392.680365981729;6394.360563943615;6396.552241343108;6403.7438502459845;6404.167458230447;4503.369528266013;4516.909661806778;4518.615917647004;4519.344695019204;4520.208963431386;6505.121808555832;6556.967215163893;6614.0740296358545;6621.304762999864;6621.768238111691;4645.605123564535;4647.047355292157;4651.790669419885;4655.208614535537;4658.301254811738;4711.52884711533;4714.245720684674;4717.009428020086;4720.12677654635;4720.150372932905;5385.946140538623;5395.271638328853;5399.568034557226;5410.053240682865;5411.134218525017;6257.374262573706;6359.046143078575;6401.199850018747;6414.1340918976275;6415.3263907289065;1631.4697723239995;1669.3155806119432;1669.4157045034142;1670.582354411397;1670.874684398675;1097.8023955688052;1117.8211486162174;1122.1359553144034;1123.6179698902413;1142.3431526871916;6210.534209934273;6211.409916057967;6216.533759968021;6217.9689101555105;6229.813105606839;6332.271788744353;6554.442872355808;6577.769778057745;6616.305287944792;6638.137042184485;6087.178230938855;6148.292946311142;6263.71813268404;6279.372062793684;6286.779962701284;3507.9649203508147;3561.6082230954576;3563.7861728296384;3566.98216508921;3569.1790888095366;6722;6834.692438840264;6840.6921688524135;6841.350071743176;6849.554778939353;6794.184697836291;6795.286494918061;6796.422304104108;6800.217974932847;6802.455803535705;6828.419584335288;6830.453563714891;6830.897536536956;6833.282505336968;6841.110655614738;6447.323031081669;6562.015697645392;6609.239937407259;6620.933790662224;6624.436922861611;6518.380753828379;6540.705668244846;6565.934340656532;6568.770093046721;6577.440917522064;6877.071595334566;6879.862402752017;6883.621400823003;6887.449004079562;6893.0694356261265;4631.791569379453;4645.117427688729;4660.300954856799;4669.789859456265;4675.625949924066;6575.079488871466;6765.357291057342;6773.424258938046;6782.932170678428;6783.152105986752;6458.61248325102;6532.967335163294;6533.705983230671;6547.377999090078;6550;6292.370762923671;6520.576162549419;6521.119648847345;6526.314736952551;6529.575577587441;6376.681165941592;6382.074599183;6410.45511131559;6412.557174635856;6424.903626445701;6523.902141467976;6542.509311801729;6554.410103090745;6556.377144171296;6564.4420224281475;6258.43674069336;6385.776497822554;6389.4888408926245;6391.169148010729;6399.200099987502 09:25:38 INFO - PROCESS | 16740 | |5;yui.html;857.8412993596187;876.7676565710088;917.6146018672157;919.8850143732034;930.2418528898943;13093.476260949563;13237.470501179941;13240.999999999987;13251.46994120234;13273.402696878651;13162.144460610054;13296.069275150743;13301.534446294381;13328.40022199001;13333.333333333343;715.2275542414193;725.9274072592725;846.1792061700141;864.2999170671776;864.360373323739;839.95380254086;840.8528507511191;842.3345557009955;842.5871323051722;843.6920524008723;760.5360729954723;767.57399643198;910.2945217456459;914.5564401265381;914.8444764390069;892.6652505310509;903.5301643145593;908.4458480326995;909.4361495872536;909.5179554835914;671.0437626382386;671.4091599392552;671.4158681946689;671.516508114157;672.3411057163958;14145.929270353685;14446.566603001927;14474.203918784462;14531.128132312095;14616.12303261797;13935.303234838217;13974.510892118727;13992.30038498071;14071.929640351835;14337.71324573513;11052.281601695944;11052.834207486894;11181.608643697431;11420.200585958986;11557.24877882935;3845.1540661054523;3845.7115716321387;3889.6499315061496;3900.0639846436934;3942;49998.50001499975;50223;50377.74811125958;50400.74799626015;51223.23165152526;10415.218858585637;10755.247132700635;10756.56973721058;10981.835272470918;11082.944585277184;18759;18776.436706898814;18798.96605686673;18805.15376808047;19627;7672.079350477867;7789;7808.921910780819;7868.72459463916;8018.158093400109;4301.440812694393;4345.630621397154;4371.322445021026;4417.801198946056;4595.241785105421;3221.3074189049466;3228.047725920859;3229;3260.6413294537583;3264.1186879542392;659.0707102984768;659.8251463362213;684.090160087083;684.2370756606348;684.4524380495584;685.5543896467236;702.9578225306498;704.8378872859168;720.4884531982246;728.7667946257146;8909.376343655882;9085.682001129928;9154.450732956044;9216.216621587106;9282.767930801783;600.1172414478318;602.5119653081018;678.9781379024635;748.1994266135183;750.9286617771303;4328.8484903028875;4483.327500874895;4483.453208643013;4528.388667529907;4569.954300456953;54673;55019.72490137524;55048;55343;55360;49118.26322605164;49650.255246171335;49784.25323620148;50355.24467132996;50642.493575064516;44532.77733611311;44944.77527612406;45007.32489012668;45896.770516147204;46061.76969115133;8890.86663700058;9010.684626038057;9149.862752058858;9168.770780730536;9385.671501497414;33354.83322583372;33837.830810846775;33992.83003584966;34228.48657270093;34312.48531271983;38222;50089.74955125201;51928.22107668313;52080.21879671732;52293;12023.81964270554;12075.215111018108;12296.938515307365;12457.875421245672;12491.625251242478;12957.22256664603;13179.275139867015;13195.20828750278;13277.269750163834;13534.255615941218;12121.333326667118;12290.139690221598;12633.494660213488;12838.29389383593;13050.477980880658;12803.167794093353;12833.294168820805;12852.55016074433;12867.742645147231;13234.205947643171;12748.362581870684;12861.807072894102;12879.871201287868;12926.224426534438;13107.475700971854;10157.136643385395;10317.845232321371;10773.676789696321;10799.40603266828;10905.618303359344;10547.103496202908;10636.202284828823;10731.785364292826;10790.514426850968;10828.512716927915 09:25:38 INFO - PROCESS | 16740 | __end_tp_report 09:25:38 INFO - PROCESS | 16740 | __start_cc_report 09:25:38 INFO - PROCESS | 16740 | _x_x_mozilla_cycle_collect,679 09:25:38 INFO - PROCESS | 16740 | __end_cc_report 09:25:38 INFO - PROCESS | 16740 | __startTimestamp1457025938982__endTimestamp 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | ------- Summary: start ------- 09:25:38 INFO - PROCESS | 16740 | Number of tests: 6 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | [#0] dojo.html Cycles:165 Average:5288.45 Median:5478.26 stddev:574.41 (10.5%) stddev-sans-first:552.86 09:25:38 INFO - PROCESS | 16740 | Values: 3223.8 3249.3 3273.6 3276.7 3297.7 5180.4 5185.9 5186.2 5206.8 5212.7 5211.5 5237.2 5237.7 5244.8 5276.5 4691.5 4789.2 4828.5 4828.6 4877.6 4746.4 4772.3 4781.2 4783.7 4796.5 4737.7 4745.8 4804.2 4836.7 4845.7 4907.7 4980.4 4986.3 4994.6 4995.2 4754.9 4756.2 4785.9 4789.5 4832.3 4936.1 4954.0 5041.9 5105.5 5116.2 5667.5 5713.4 5724.2 5724.5 5726.5 5740.3 5750.5 5753.0 5756.8 5765.8 5288.6 5337.5 5362.2 5427.6 5451.8 5857.9 5883.5 5896.7 5897.0 5897.3 5495.3 5625.9 5631.3 5632.0 5659.3 5635.2 5714.3 5773.8 5780.5 5811.4 5535.6 5544.1 5544.4 5548.3 5551.2 5520.3 5529.1 5529.4 5603.7 5691.2 4393.6 4410.1 4432.2 4448.4 4453.3 3960.4 4005.0 4016.2 4017.8 4033.4 5331.6 5414.5 5441.2 5465.3 5528.5 5732.6 5732.7 5750.8 5762.6 5763.8 5425.4 5437.2 5486.2 5487.6 5488.1 4790.9 4862.0 4975.1 5006.7 5044.3 5884.4 5911.7 5921.0 5928.8 5930.0 5712.3 5747.6 5748.7 5750.2 5751.2 5754.4 5812.9 5840.2 5881.7 5908.6 5588.4 5636.1 5641.3 5643.9 5648.0 5485.8 5684.3 5693.2 5724.8 5727.5 5516.4 5538.1 5589.1 5606.9 5623.9 5472.4 5476.6 5478.3 5481.3 5499.6 5380.8 5408.3 5438.6 5443.5 5531.6 5600.8 5685.3 5706.1 5714.1 5747.5 5458.5 5459.9 5462.6 5463.8 5473.6 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | [#1] ext.html Cycles:180 Average:46003.25 Median:40752.49 stddev:70519.04 (173.0%) stddev-sans-first:70634.26 09:25:38 INFO - PROCESS | 16740 | Values: 545.5 548.6 556.1 572.6 587.5 31174.4 39617.6 39984.0 40030.6 40053.2 43056.4 43807.3 43912.6 44003.8 44069.3 8644.7 8823.7 8890.0 8898.7 8902.0 8463.7 8632.9 8645.3 8646.7 8862.2 8852.8 8899.1 8935.6 8939.7 8942.4 8822.5 8883.6 8887.9 8895.3 8898.7 8786.6 8932.4 8942.6 8954.1 8962.3 43827.6 44181.3 44271.6 44274.1 44377.1 3813.6 3835.3 4061.4 4267.9 4387.6 33031.5 33305.0 33527.8 33842.8 33967.0 4118.9 4180.8 4200.7 4204.3 4206.4 292073.0 292270.0 292580.0 293008.0 293306.0 61180.4 62314.7 64803.0 65416.3 68140.0 59431.7 59439.2 59654.0 59679.4 59876.0 18704.3 18934.5 18989.3 18996.4 19040.5 12800.3 12807.6 12831.4 12837.7 12841.9 22135.0 22307.7 22488.8 22504.8 22511.1 7781.1 7907.1 8021.2 8040.6 8102.8 945.9 969.4 1061.6 1077.9 1150.8 30392.0 30479.4 30680.3 30681.7 31215.4 1125.1 1143.3 1149.6 1229.4 1283.5 13935.5 13997.4 15895.4 16237.0 16517.8 269982.0 272540.0 272571.0 272731.0 272895.0 53872.0 54089.7 54105.5 54121.0 54140.5 742.4 1338.6 1391.3 1440.2 1550.9 27671.4 29325.6 29530.4 29743.0 30202.2 43369.3 43449.0 43456.8 43468.8 44919.8 247049.0 247208.0 247418.0 248732.0 248857.0 33972.8 34614.3 34618.5 34762.5 34929.3 47638.3 48511.8 48572.3 48920.5 49380.5 40333.6 40596.8 40815.6 41381.2 41823.2 39912.4 41026.8 41047.7 41220.8 41698.0 39890.4 40403.8 40689.4 40878.2 41190.4 31078.2 32525.3 33586.8 34562.0 35847.5 27697.3 28565.3 28910.8 29251.7 29547.4 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | [#2] jquery.html Cycles:180 Average:30979.36 Median:34980.24 stddev:105421.80 (301.4%) stddev-sans-first:105692.49 09:25:38 INFO - PROCESS | 16740 | Values: 380.0 392.9 394.7 395.6 397.4 14113.2 14693.4 16528.0 18815.7 18862.7 17140.7 17313.3 17360.7 18309.7 18333.9 6097.6 6305.0 6312.0 6337.0 6338.7 4607.8 4735.4 4740.8 6203.7 6803.1 5359.9 5557.8 7012.8 7047.0 7054.6 5542.9 5561.8 5562.8 5573.2 5582.3 5279.7 5486.4 5489.4 5490.5 5492.9 13789.2 15363.5 20327.5 21095.7 21272.7 2210.5 2228.6 2235.2 2293.4 2354.9 10258.7 11180.5 12233.8 13624.1 13818.0 1916.2 1936.2 1946.8 2023.1 2048.0 60300.0 60596.0 62074.7 62277.4 62905.4 10987.7 11061.2 11061.3 11061.8 11142.9 14689.2 14696.6 14774.3 14823.3 14841.0 8380.6 8380.7 8407.2 8415.5 8436.5 8066.6 8094.6 8099.4 8100.6 8115.2 4438.7 4640.7 4789.1 4889.3 4899.7 1871.8 1940.2 1964.7 1969.9 1976.8 956.3 1003.0 1062.9 1065.5 1101.5 15744.9 16177.8 16702.0 17302.6 17835.0 954.8 965.7 978.6 983.4 1083.2 9046.5 9353.7 9460.9 10043.6 10737.5 632166.0 632944.0 646968.0 650619.0 667970.0 47268.3 68243.0 75567.2 75640.6 77641.0 1192.5 1196.7 1202.8 1216.7 1224.0 15041.8 15816.8 16050.4 16286.9 16335.4 33954.7 34063.5 34818.8 35141.6 35820.8 41755.0 41774.0 42627.4 42772.4 43126.4 7873.3 7873.5 8062.2 8108.4 8181.9 8585.8 11018.9 11039.7 11327.7 11610.4 10081.5 10134.9 10314.6 10347.9 10611.2 8472.3 9969.8 10480.6 10504.9 10744.2 10357.4 10537.8 10718.5 10780.8 10943.2 7791.6 8051.7 8084.5 8120.3 8134.6 9575.3 10520.5 10534.2 10679.5 10771.6 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | [#3] mootools.html Cycles:180 Average:5822.73 Median:559.17 stddev:3048.42 (545.2%) stddev-sans-first:3038.88 09:25:38 INFO - PROCESS | 16740 | Values: 1404.5 1533.7 1574.8 1775.4 1878.0 7401.1 7930.4 8097.7 8111.1 8118.9 7915.6 8051.8 8097.4 8098.6 8106.1 2440.1 2472.3 2473.2 2474.5 2475.5 2345.8 2370.1 2457.1 2459.9 2461.2 2373.0 2396.2 2406.0 2417.0 2432.8 2367.2 2388.7 2393.5 2395.1 2436.0 2328.4 2330.7 2332.7 2338.2 2355.3 8279.8 8308.8 8321.7 8322.8 8325.3 5072.2 5183.0 5189.1 5195.1 5200.8 3324.7 3349.2 3412.7 3424.3 3450.5 1141.0 1197.2 1201.2 1228.7 1243.1 11790.6 11791.8 11802.9 11818.8 11830.0 4609.4 4644.9 4649.3 4673.9 4745.6 9893.7 9903.8 9907.4 9913.5 9933.1 5071.5 5105.1 5114.7 5115.8 5116.1 4165.0 4209.2 4599.3 4649.0 4706.7 3580.8 3593.5 3639.7 3643.9 3679.6 1207.8 1257.2 1261.9 1275.1 1279.7 1429.9 1433.2 1433.5 1443.9 1449.1 7821.8 7844.3 7876.4 7883.1 7887.7 541.6 550.3 551.5 558.9 559.4 6308.3 6332.3 6336.7 6349.7 6371.0 9871.2 10024.7 10130.6 10131.9 10161.9 8974.6 9956.4 10086.5 10103.2 10138.6 7792.3 7806.0 7852.9 7875.1 7972.1 7560.8 7669.0 7842.1 7873.3 7907.4 8631.1 8866.6 8921.6 8937.5 9043.2 9030.0 9266.8 9304.0 9459.6 9467.2 6445.4 6561.2 6904.9 7175.7 7305.2 7537.1 7562.7 7585.7 7608.3 7611.0 7278.1 7376.2 7385.1 7427.3 7579.2 7212.8 7226.6 7326.9 7415.5 7508.9 7591.6 7668.5 7683.6 7686.5 7692.8 7079.6 7137.2 7187.4 7189.4 7192.6 7068.1 7083.2 7124.9 7140.6 7143.7 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | [#4] prototype.html Cycles:180 Average:5388.64 Median:6117.74 stddev:1609.74 (26.3%) stddev-sans-first:1569.18 09:25:38 INFO - PROCESS | 16740 | Values: 348.8 358.1 359.1 359.6 360.6 5990.5 6032.2 6034.5 6039.7 6087.1 5696.9 5810.6 5837.9 5916.2 5979.1 4649.4 4665.3 4668.0 4672.1 4682.1 4462.6 4685.7 4687.3 4691.7 4696.7 4494.4 4499.4 4552.7 4557.3 4560.1 4582.0 4603.9 4608.1 4613.9 4615.8 4099.5 4106.3 4107.5 4107.9 4111.4 5564.2 5697.5 5708.3 5708.7 5713.3 5387.7 5389.8 5401.8 5405.1 5406.3 6392.7 6394.4 6396.6 6403.7 6404.2 4503.4 4516.9 4518.6 4519.3 4520.2 6505.1 6557.0 6614.1 6621.3 6621.8 4645.6 4647.0 4651.8 4655.2 4658.3 4711.5 4714.2 4717.0 4720.1 4720.2 5385.9 5395.3 5399.6 5410.1 5411.1 6257.4 6359.0 6401.2 6414.1 6415.3 1631.5 1669.3 1669.4 1670.6 1670.9 1097.8 1117.8 1122.1 1123.6 1142.3 6210.5 6211.4 6216.5 6218.0 6229.8 6332.3 6554.4 6577.8 6616.3 6638.1 6087.2 6148.3 6263.7 6279.4 6286.8 3508.0 3561.6 3563.8 3567.0 3569.2 6722.0 6834.7 6840.7 6841.4 6849.6 6794.2 6795.3 6796.4 6800.2 6802.5 6828.4 6830.5 6830.9 6833.3 6841.1 6447.3 6562.0 6609.2 6620.9 6624.4 6518.4 6540.7 6565.9 6568.8 6577.4 6877.1 6879.9 6883.6 6887.4 6893.1 4631.8 4645.1 4660.3 4669.8 4675.6 6575.1 6765.4 6773.4 6782.9 6783.2 6458.6 6533.0 6533.7 6547.4 6550.0 6292.4 6520.6 6521.1 6526.3 6529.6 6376.7 6382.1 6410.5 6412.6 6424.9 6523.9 6542.5 6554.4 6556.4 6564.4 6258.4 6385.8 6389.5 6391.2 6399.2 09:25:38 INFO - PROCESS | 16740 | 09:25:38 INFO - PROCESS | 16740 | [#5] yui.html Cycles:180 Average:14276.26 Median:24475.29 stddev:15911.45 (65.0%) stddev-sans-first:15924.17 09:25:38 INFO - PROCESS | 16740 | Values: 857.8 876.8 917.6 919.9 930.2 13093.5 13237.5 13241.0 13251.5 13273.4 13162.1 13296.1 13301.5 13328.4 13333.3 715.2 725.9 846.2 864.3 864.4 840.0 840.9 842.3 842.6 843.7 760.5 767.6 910.3 914.6 914.8 892.7 903.5 908.4 909.4 909.5 671.0 671.4 671.4 671.5 672.3 14145.9 14446.6 14474.2 14531.1 14616.1 13935.3 13974.5 13992.3 14071.9 14337.7 11052.3 11052.8 11181.6 11420.2 11557.2 3845.2 3845.7 3889.6 3900.1 3942.0 49998.5 50223.0 50377.7 50400.7 51223.2 10415.2 10755.2 10756.6 10981.8 11082.9 18759.0 18776.4 18799.0 18805.2 19627.0 7672.1 7789.0 7808.9 7868.7 8018.2 4301.4 4345.6 4371.3 4417.8 4595.2 3221.3 3228.0 3229.0 3260.6 3264.1 659.1 659.8 684.1 684.2 684.5 685.6 703.0 704.8 720.5 728.8 8909.4 9085.7 9154.5 9216.2 9282.8 600.1 602.5 679.0 748.2 750.9 4328.8 4483.3 4483.5 4528.4 4570.0 54673.0 55019.7 55048.0 55343.0 55360.0 49118.3 49650.3 49784.3 50355.2 50642.5 44532.8 44944.8 45007.3 45896.8 46061.8 8890.9 9010.7 9149.9 9168.8 9385.7 33354.8 33837.8 33992.8 34228.5 34312.5 38222.0 50089.7 51928.2 52080.2 52293.0 12023.8 12075.2 12296.9 12457.9 12491.6 12957.2 13179.3 13195.2 13277.3 13534.3 12121.3 12290.1 12633.5 12838.3 13050.5 12803.2 12833.3 12852.6 12867.7 13234.2 12748.4 12861.8 12879.9 12926.2 13107.5 10157.1 10317.8 10773.7 10799.4 10905.6 10547.1 10636.2 10731.8 10790.5 10828.5 09:25:38 INFO - PROCESS | 16740 | -------- Summary: end -------- 09:25:38 INFO - PROCESS | 16740 | 09:25:39 INFO - TEST-INFO | 16740: exit 0 09:25:39 INFO - TEST-OK | dromaeo_css | took 1109557ms 09:25:39 INFO - TEST-START | kraken 09:25:39 INFO - operating with platform_type : linux_ 09:25:39 INFO - Initialising browser for kraken test... 09:25:39 INFO - TEST-INFO | started process 16939 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp4bx5Y/profile http://localhost:47614/getInfo.html) 09:25:43 INFO - PROCESS | 16939 | __metrics Screen width/height:1600/1200 09:25:43 INFO - PROCESS | 16939 | colorDepth:24 09:25:43 INFO - PROCESS | 16939 | Browser inner width/height: 1024/697 09:25:43 INFO - PROCESS | 16939 | __metrics 09:25:43 INFO - PROCESS | 16939 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:25:44 INFO - TEST-INFO | 16939: exit 0 09:25:44 INFO - Browser initialized. 09:25:44 INFO - Running cycle 1/1 for kraken test... 09:25:44 INFO - TEST-INFO | started process 17034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp4bx5Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:56 INFO - PROCESS | 17034 | RSS: Main: 136044544 09:25:56 INFO - PROCESS | 17034 | 09:25:58 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-beat-detection) 09:25:59 INFO - PROCESS | 17034 | RSS: Main: 169975808 09:25:59 INFO - PROCESS | 17034 | 09:26:01 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-dft) 09:26:02 INFO - PROCESS | 17034 | RSS: Main: 169664512 09:26:02 INFO - PROCESS | 17034 | 09:26:05 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-fft) 09:26:05 INFO - PROCESS | 17034 | RSS: Main: 165937152 09:26:05 INFO - PROCESS | 17034 | 09:26:07 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47614/tests/kraken/driver.html?testName=audio-oscillator) 09:26:08 INFO - PROCESS | 17034 | RSS: Main: 164777984 09:26:08 INFO - PROCESS | 17034 | 09:26:10 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47614/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:10 INFO - PROCESS | 17034 | RSS: Main: 164888576 09:26:10 INFO - PROCESS | 17034 | 09:26:13 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47614/tests/kraken/driver.html?testName=imaging-darkroom) 09:26:14 INFO - PROCESS | 17034 | RSS: Main: 165683200 09:26:14 INFO - PROCESS | 17034 | 09:26:17 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47614/tests/kraken/driver.html?testName=imaging-desaturate) 09:26:18 INFO - PROCESS | 17034 | RSS: Main: 165085184 09:26:18 INFO - PROCESS | 17034 | 09:26:21 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47614/tests/kraken/driver.html?testName=json-parse-financial) 09:26:21 INFO - PROCESS | 17034 | RSS: Main: 165502976 09:26:21 INFO - PROCESS | 17034 | 09:26:23 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47614/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:26:23 INFO - PROCESS | 17034 | RSS: Main: 168443904 09:26:23 INFO - PROCESS | 17034 | 09:26:25 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:26:26 INFO - PROCESS | 17034 | RSS: Main: 166817792 09:26:26 INFO - PROCESS | 17034 | 09:26:28 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:26:28 INFO - PROCESS | 17034 | RSS: Main: 173379584 09:26:28 INFO - PROCESS | 17034 | 09:26:31 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:26:31 INFO - PROCESS | 17034 | RSS: Main: 178900992 09:26:31 INFO - PROCESS | 17034 | 09:26:34 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:26:34 INFO - PROCESS | 17034 | RSS: Main: 175915008 09:26:34 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | Cycle 1(1): loaded http://localhost:47614/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47614/tests/kraken/driver.html?testName=ai-astar) 09:26:36 INFO - PROCESS | 17034 | RSS: Main: 238247936 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | __start_tp_report 09:26:36 INFO - PROCESS | 17034 | _x_x_mozilla_page_load 09:26:36 INFO - PROCESS | 17034 | _x_x_mozilla_page_load_details 09:26:36 INFO - PROCESS | 17034 | |i|pagename|runs| 09:26:36 INFO - PROCESS | 17034 | |0;ai-astar;109;110;105;112;111;110;101;110;111;111 09:26:36 INFO - PROCESS | 17034 | |1;audio-beat-detection;159;123;148;155;124;159;147;129;168;125 09:26:36 INFO - PROCESS | 17034 | |2;audio-dft;161;159;161;161;162;161;158;161;162;160 09:26:36 INFO - PROCESS | 17034 | |3;audio-fft;77;77;73;147;74;77;75;74;146;73 09:26:36 INFO - PROCESS | 17034 | |4;audio-oscillator;95;94;136;94;95;95;141;94;96;145 09:26:36 INFO - PROCESS | 17034 | |5;imaging-gaussian-blur;109;109;109;109;109;110;109;109;108;109 09:26:36 INFO - PROCESS | 17034 | |6;imaging-darkroom;147;146;146;146;147;146;147;146;145;146 09:26:36 INFO - PROCESS | 17034 | |7;imaging-desaturate;96;96;96;96;95;95;95;96;95;96 09:26:36 INFO - PROCESS | 17034 | |8;json-parse-financial;55;56;57;54;53;56;58;55;53;53 09:26:36 INFO - PROCESS | 17034 | |9;json-stringify-tinderbox;64;65;64;64;65;65;64;65;64;67 09:26:36 INFO - PROCESS | 17034 | |10;stanford-crypto-aes;77;78;86;79;79;79;80;79;79;79 09:26:36 INFO - PROCESS | 17034 | |11;stanford-crypto-ccm;115;129;113;111;111;112;111;110;108;110 09:26:36 INFO - PROCESS | 17034 | |12;stanford-crypto-pbkdf2;131;130;136;130;131;134;132;132;131;133 09:26:36 INFO - PROCESS | 17034 | |13;stanford-crypto-sha256-iterative;64;67;64;65;65;65;65;65;62;65 09:26:36 INFO - PROCESS | 17034 | __end_tp_report 09:26:36 INFO - PROCESS | 17034 | __start_cc_report 09:26:36 INFO - PROCESS | 17034 | _x_x_mozilla_cycle_collect,949 09:26:36 INFO - PROCESS | 17034 | __end_cc_report 09:26:36 INFO - PROCESS | 17034 | __startTimestamp1457025996509__endTimestamp 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | ------- Summary: start ------- 09:26:36 INFO - PROCESS | 17034 | Number of tests: 14 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.50 stddev:3.40 (3.1%) stddev-sans-first:3.61 09:26:36 INFO - PROCESS | 17034 | Values: 109.0 110.0 105.0 112.0 111.0 110.0 101.0 110.0 111.0 111.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:151.50 stddev:16.99 (11.2%) stddev-sans-first:17.10 09:26:36 INFO - PROCESS | 17034 | Values: 159.0 123.0 148.0 155.0 124.0 159.0 147.0 129.0 168.0 125.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 09:26:36 INFO - PROCESS | 17034 | Values: 161.0 159.0 161.0 161.0 162.0 161.0 158.0 161.0 162.0 160.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#3] audio-fft Cycles:10 Average:89.30 Median:74.50 stddev:30.19 (40.5%) stddev-sans-first:31.69 09:26:36 INFO - PROCESS | 17034 | Values: 77.0 77.0 73.0 147.0 74.0 77.0 75.0 74.0 146.0 73.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#4] audio-oscillator Cycles:10 Average:108.50 Median:94.50 stddev:22.31 (23.6%) stddev-sans-first:23.12 09:26:36 INFO - PROCESS | 17034 | Values: 95.0 94.0 136.0 94.0 95.0 95.0 141.0 94.0 96.0 145.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 09:26:36 INFO - PROCESS | 17034 | Values: 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 108.0 109.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 09:26:36 INFO - PROCESS | 17034 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 145.0 146.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:26:36 INFO - PROCESS | 17034 | Values: 96.0 96.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 96.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:1.76 (3.2%) stddev-sans-first:1.87 09:26:36 INFO - PROCESS | 17034 | Values: 55.0 56.0 57.0 54.0 53.0 56.0 58.0 55.0 53.0 53.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.95 (1.5%) stddev-sans-first:0.97 09:26:36 INFO - PROCESS | 17034 | Values: 64.0 65.0 64.0 64.0 65.0 65.0 64.0 65.0 64.0 67.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:2.42 (3.1%) stddev-sans-first:2.39 09:26:36 INFO - PROCESS | 17034 | Values: 77.0 78.0 86.0 79.0 79.0 79.0 80.0 79.0 79.0 79.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#11] stanford-crypto-ccm Cycles:10 Average:113.00 Median:111.50 stddev:5.93 (5.3%) stddev-sans-first:6.24 09:26:36 INFO - PROCESS | 17034 | Values: 115.0 129.0 113.0 111.0 111.0 112.0 111.0 110.0 108.0 110.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:132.00 Median:132.00 stddev:1.89 (1.4%) stddev-sans-first:1.96 09:26:36 INFO - PROCESS | 17034 | Values: 131.0 130.0 136.0 130.0 131.0 134.0 132.0 132.0 131.0 133.0 09:26:36 INFO - PROCESS | 17034 | 09:26:36 INFO - PROCESS | 17034 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:1.25 (1.9%) stddev-sans-first:1.30 09:26:36 INFO - PROCESS | 17034 | Values: 64.0 67.0 64.0 65.0 65.0 65.0 65.0 65.0 62.0 65.0 09:26:36 INFO - PROCESS | 17034 | -------- Summary: end -------- 09:26:36 INFO - PROCESS | 17034 | 09:26:37 INFO - TEST-INFO | 17034: exit 0 09:26:37 INFO - TEST-OK | kraken | took 57752ms 09:26:37 INFO - TEST-START | v8_7 09:26:37 INFO - operating with platform_type : linux_ 09:26:37 INFO - Initialising browser for v8_7 test... 09:26:37 INFO - TEST-INFO | started process 17288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCs0Kgg/profile http://localhost:47614/getInfo.html) 09:26:41 INFO - PROCESS | 17288 | __metrics Screen width/height:1600/1200 09:26:41 INFO - PROCESS | 17288 | colorDepth:24 09:26:41 INFO - PROCESS | 17288 | Browser inner width/height: 1024/697 09:26:41 INFO - PROCESS | 17288 | __metrics 09:26:41 INFO - PROCESS | 17288 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:42 INFO - TEST-INFO | 17288: exit 0 09:26:42 INFO - Browser initialized. 09:26:42 INFO - Running cycle 1/1 for v8_7 test... 09:26:42 INFO - TEST-INFO | started process 17378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCs0Kgg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:53 INFO - PROCESS | 17378 | RSS: Main: 136069120 09:26:53 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | Cycle 1(1): loaded http://localhost:47614/tests/v8_7/run.html (next: http://localhost:47614/tests/v8_7/run.html) 09:27:16 INFO - PROCESS | 17378 | RSS: Main: 421281792 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | __start_tp_report 09:27:16 INFO - PROCESS | 17378 | _x_x_mozilla_page_load 09:27:16 INFO - PROCESS | 17378 | _x_x_mozilla_page_load_details 09:27:16 INFO - PROCESS | 17378 | |i|pagename|runs| 09:27:16 INFO - PROCESS | 17378 | |0;Richards;166.61112962345885 09:27:16 INFO - PROCESS | 17378 | |1;DeltaBlue;169.80811682798438 09:27:16 INFO - PROCESS | 17378 | |2;Encrypt;281.0567734682406 09:27:16 INFO - PROCESS | 17378 | |3;Decrypt;5229.166666666667 09:27:16 INFO - PROCESS | 17378 | |4;RayTrace;952.3809523809524 09:27:16 INFO - PROCESS | 17378 | |5;Earley;549.7526113249038 09:27:16 INFO - PROCESS | 17378 | |6;Boyer;10578.947368421053 09:27:16 INFO - PROCESS | 17378 | |7;RegExp;40740 09:27:16 INFO - PROCESS | 17378 | |8;Splay;498.00796812749 09:27:16 INFO - PROCESS | 17378 | |9;NavierStokes;4817.307692307692 09:27:16 INFO - PROCESS | 17378 | __end_tp_report 09:27:16 INFO - PROCESS | 17378 | __startTimestamp1457026036410__endTimestamp 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | ------- Summary: start ------- 09:27:16 INFO - PROCESS | 17378 | Number of tests: 10 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#0] Richards Cycles:1 Average:166.61 Median:166.61 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 166.6 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#1] DeltaBlue Cycles:1 Average:169.81 Median:169.81 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 169.8 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#2] Encrypt Cycles:1 Average:281.06 Median:281.06 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 281.1 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#3] Decrypt Cycles:1 Average:5229.17 Median:5229.17 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 5229.2 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#4] RayTrace Cycles:1 Average:952.38 Median:952.38 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 952.4 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#5] Earley Cycles:1 Average:549.75 Median:549.75 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 549.8 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#6] Boyer Cycles:1 Average:10578.95 Median:10578.95 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 10578.9 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#7] RegExp Cycles:1 Average:40740.00 Median:40740.00 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 40740.0 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#8] Splay Cycles:1 Average:498.01 Median:498.01 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 498.0 09:27:16 INFO - PROCESS | 17378 | 09:27:16 INFO - PROCESS | 17378 | [#9] NavierStokes Cycles:1 Average:4817.31 Median:4817.31 stddev:0.00 (0.0%) 09:27:16 INFO - PROCESS | 17378 | Values: 4817.3 09:27:16 INFO - PROCESS | 17378 | -------- Summary: end -------- 09:27:16 INFO - PROCESS | 17378 | 09:27:17 INFO - TEST-INFO | 17378: exit 0 09:27:17 INFO - TEST-OK | v8_7 | took 39827ms 09:27:17 INFO - SUITE-END | took 1207s 09:27:17 INFO - Completed test suite (00:20:07) 09:27:17 INFO - Working with test: dromaeo_css 09:27:17 INFO - Generating results file: dromaeo_css 09:27:17 INFO - Working with test: kraken 09:27:17 INFO - Generating results file: kraken 09:27:17 INFO - javascript benchmark 09:27:17 INFO - Working with test: v8_7 09:27:17 INFO - Generating results file: v8_7 09:27:17 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:27:17 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,181,35]] 09:27:17 INFO - process_Request line: dromaeo_css 7185.32 graph.html#tests=[[72,181,35]] 09:27:17 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:27:17 INFO - process_Request line: kraken 1470.80 graph.html#tests=[[232,181,35]] 09:27:17 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:27:17 INFO - process_Request line: v8_7 22121.89 graph.html#tests=[[230,181,35]] 09:27:17 INFO - RETURN: dromaeo_css: 7185.32 09:27:17 INFO - RETURN: kraken: 1470.80 09:27:17 INFO - RETURN: v8_7: 22121.89 09:27:17 INFO - javascript benchmark 09:27:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3223.8388080595973, 3249.2689144942406, 3273.5744353234077, 3276.7378609711227, 3297.736181105511, 5180.430152683211, 5185.948140518593, 5186.247994040862, 5206.7656955437005, 5212.687238765677, 5211.5309622134, 5237.240600112981, 5237.685738855671, 5244.777953747232, 5276.525112739852, 4691.530846915315, 4789.2097803862325, 4828.468868424462, 4828.56542911138, 4877.585405240558, 4746.359241502405, 4772.331873537708, 4781.163296423129, 4783.7129772213775, 4796.544328288807, 4737.692050016738, 4745.810167593309, 4804.20730579454, 4836.709797412149, 4845.685030473008, 4907.730074845882, 4980.377452818398, 4986.252062190664, 4994.575461085812, 4995.225740010304, 4754.881127971794, 4756.191447453941, 4785.880352991168, 4789.49710280419, 4832.251001094836, 4936.136176169156, 4954.033963377143, 5041.899162016739, 5105.5149760772665, 5116.2069879168785, 5667.546596272329, 5713.400092990218, 5724.198612194296, 5724.484796368347, 5726.513246374063, 5740.311162660466, 5750.539956803445, 5752.9712351437975, 5756.769729210827, 5765.769369225268, 5288.629795914288, 5337.5196232338885, 5362.2224777407055, 5427.565794736412, 5451.8364449066985, 5857.912131318034, 5883.529317654577, 5896.6756828374855, 5897.0, 5897.262842144732, 5495.340559132929, 5625.8593535161945, 5631.2960879890015, 5631.958087753778, 5659.292588426447, 5635.239242702265, 5714.2571465710025, 5773.797917072882, 5780.450857168562, 5811.4479124483105, 5535.5848311376, 5544.085225937756, 5544.417836127229, 5548.278723765965, 5551.195076713854, 5520.309961254843, 5529.087700529448, 5529.419410961871, 5603.7478313475995, 5691.203231547586, 4393.648508119407, 4410.051838854598, 4432.202203603318, 4448.377227188196, 4453.287474004143, 3960.405939109157, 4005.0, 4016.216837716617, 4017.7589344639414, 4033.3748269018347, 5331.573474122139, 5414.458554144633, 5441.183822426588, 5465.289512363367, 5528.502434780889, 5732.57005724564, 5732.742026608813, 5750.7987220447085, 5762.625429347079, 5763.769449221984, 5425.430329815392, 5437.184422336602, 5486.204500347507, 5487.588430867621, 5488.094464413327, 4790.856274311776, 4861.975690121527, 4975.0547395994645, 5006.749662516932, 5044.2937988681615, 5884.382139875252, 5911.674857882857, 5921.0, 5928.822135335947, 5930.021546444788, 5712.343080545707, 5747.597668163188, 5748.655080695172, 5750.223719797774, 5751.166080918327, 5754.395788442153, 5812.912806307824, 5840.1531777892815, 5881.735321910439, 5908.586398952204, 5588.385277619377, 5636.070048442125, 5641.26663533738, 5643.858903527444, 5647.983362994536, 5485.807996720256, 5684.346300175533, 5693.231413759089, 5724.79963201286, 5727.513161381329, 5516.420775818481, 5538.0585300499815, 5589.077802162597, 5606.85982850432, 5623.943760562342, 5472.398036215934, 5476.644018138808, 5478.260434841245, 5481.314835645544, 5499.642523235977, 5380.811671591476, 5408.323959505062, 5438.592105592175, 5443.455654434525, 5531.640443371168, 5600.831975040755, 5685.260916080883, 5706.058500347563, 5714.114312281603, 5747.453991870766, 5458.454154584669, 5459.918101228564, 5462.644928079662, 5463.808766693147, 5473.562115030708], "unit": "score", "name": "dojo.html", "value": 5252.551631263491}, {"lowerIsBetter": false, "replicates": [545.5390195284984, 548.574854487772, 556.0713608274182, 572.64209868832, 587.4918195760669, 31174.376512469764, 39617.60382396175, 39984.0, 40030.59969400305, 40053.19893602126, 43056.3541546877, 43807.3428898566, 43912.560874391325, 44003.77998110013, 44069.33895991555, 8644.740657780276, 8823.691170809023, 8890.022097569263, 8898.688545900895, 8902.0, 8463.703770368038, 8632.870506942401, 8645.308375329958, 8646.740597782045, 8862.158094980967, 8852.822943541125, 8899.065598112202, 8935.55322233889, 8939.73180804577, 8942.374033817636, 8822.470651760916, 8883.600237989305, 8887.866681999774, 8895.332850036208, 8898.688545900895, 8786.64853405863, 8932.419392739452, 8942.64229430822, 8954.05982371848, 8962.327825413055, 43827.561724382664, 44181.337279940824, 44271.557284427065, 44274.114517709466, 44377.11245775098, 3813.637704418076, 3835.271298453285, 4061.3501839705505, 4267.935980960288, 4387.62705170061, 33031.45496051289, 33305.0, 33527.83236083804, 33842.83078584616, 33967.0, 4118.897027574335, 4180.770057646859, 4200.70595058346, 4204.285271503852, 4206.3690446432975, 292073.0, 292270.0, 292580.0, 293008.0, 293306.0, 61180.38819611747, 62314.68842655758, 64803.0, 65416.34583654198, 68140.0, 59431.702841485516, 59439.216823495364, 59654.0, 59679.403205967385, 59876.0, 18704.25182992692, 18934.526636833914, 18989.335373262147, 18996.43010709681, 19040.52398690016, 12800.295983720986, 12807.551735689207, 12831.422585983653, 12837.743245135045, 12841.935790321175, 22135.0, 22307.665385018914, 22488.775112248994, 22504.774952250267, 22511.099556017904, 7781.105172905189, 7907.130215676271, 8021.197880212048, 8040.638171282307, 8102.7569172924905, 945.8817647794026, 969.3699095587783, 1061.6072053340313, 1077.8814330423647, 1150.775598758251, 30392.0, 30479.39041219163, 30680.251454623954, 30681.69318306833, 31215.375692486024, 1125.054953838763, 1143.3082984794246, 1149.6321177223208, 1229.4037391865118, 1283.4801905228398, 13935.512257070954, 13997.3701183447, 15895.364185432452, 16237.0, 16517.752233716503, 269982.0, 272540.0, 272571.0, 272731.0, 272895.0, 53872.0, 54089.72955135278, 54105.45894541083, 54121.0, 54140.45859541433, 742.3578604507062, 1338.625184948216, 1391.311300906058, 1440.2150827798666, 1550.9457168999256, 27671.446571068063, 29325.56011659784, 29530.409391812474, 29743.0, 30202.24494387658, 43369.34945975876, 43449.0, 43456.782716086214, 43468.782656086514, 44919.77540112279, 247049.0, 247208.0, 247418.0, 248732.0, 248857.0, 33972.83013584916, 34614.30771384609, 34618.48072278868, 34762.478562822085, 34929.30141397208, 47638.28542571933, 48511.75744121257, 48572.27141592808, 48920.5107948916, 49380.50619493759, 40333.596664032986, 40596.79701601473, 40815.59184408118, 41381.1723765517, 41823.16353672848, 39912.401313980896, 41026.79486602548, 41047.65608639732, 41220.79389603033, 41698.0, 39890.40164397594, 40403.797981009906, 40689.38965915573, 40878.1824363517, 41190.38214426846, 31078.22304442407, 32525.34949301048, 33586.83206583952, 34562.0, 35847.46228806518, 27697.307567310978, 28565.295527157214, 28910.760433276417, 29251.7074829249, 29547.40905181927], "unit": "score", "name": "ext.html", "value": 19429.086353524886}, {"lowerIsBetter": false, "replicates": [379.96770274526665, 392.91159489114955, 394.70199999000766, 395.55895176877783, 397.4257198348386, 14113.223772692498, 14693.412263509446, 16528.0, 18815.71776423355, 18862.717059244103, 17140.657186856257, 17313.30746770131, 17360.739588906177, 18309.725354119666, 18333.90833045833, 6097.634141951497, 6305.0, 6311.968440157816, 6337.049442583603, 6338.651374174418, 4607.838725644603, 4735.408073990751, 4740.786664600101, 6203.720832562523, 6803.0815839861725, 5359.892802143955, 5557.805476808313, 7012.754553590626, 7047.048648432447, 7054.611996340199, 5542.86142846428, 5561.77752889884, 5562.833115006556, 5573.247611572427, 5582.330120385539, 5279.656822306538, 5486.396496385434, 5489.368722596912, 5490.478404551562, 5492.862678433031, 13789.172649641054, 15363.462278820187, 20327.49181270465, 21095.68356474654, 21272.680909786366, 2210.502636906699, 2228.621134407155, 2235.2065016918923, 2293.415179129311, 2354.9411264718347, 10258.692239232834, 11180.496877640526, 12233.816492752616, 13624.114432561852, 13818.032737708365, 1916.2430839818333, 1936.1577713694587, 1946.8247857692734, 2023.1401654296865, 2047.9897600512052, 60300.0, 60596.0, 62074.689626552026, 62277.37722622806, 62905.37094629087, 10987.725306867233, 11061.170412218955, 11061.281016733887, 11061.834072488919, 11142.888571114347, 14689.19209443491, 14696.632584185481, 14774.335154917839, 14823.258837058103, 14841.0, 8380.622871970776, 8380.706675266336, 8407.243348098578, 8415.45299555527, 8436.493810371398, 8066.596670166468, 8094.635741391651, 8099.392545559107, 8100.635471403801, 8115.229053239933, 4438.733675979452, 4640.65195110368, 4789.090072886141, 4889.339939108175, 4899.681520701143, 1871.8034606366407, 1940.194819150065, 1964.65618516759, 1969.9113539890739, 1976.7924367941446, 956.3257903178243, 1003.0471052500009, 1062.8830828608848, 1065.4512925843196, 1101.498818037791, 15744.92127539355, 16177.757333640006, 16702.0, 17302.567435813955, 17835.0, 954.8472244440856, 965.7440778193784, 978.5938835383379, 983.3509883476871, 1083.1767856429012, 9046.547672616474, 9353.672621458216, 9460.905390946002, 10043.598256069676, 10737.51681174349, 632166.0, 632944.0, 646968.0, 650619.0, 667970.0, 47268.29097563539, 68243.0, 75567.24432755602, 75640.62179689066, 77641.0, 1192.5289510643247, 1196.7187710887933, 1202.8436303280519, 1216.707990082374, 1223.9755204895853, 15041.849581504483, 15816.841831581536, 16050.438234661731, 16286.918565407097, 16335.428260010842, 33954.66045339515, 34063.4890476648, 34818.82590587031, 35141.64858351384, 35820.820895895355, 41755.0, 41774.0, 42627.360589590564, 42772.358414624425, 43126.35310470283, 7873.291403773688, 7873.5275883447175, 8062.193780622126, 8108.39187060961, 8181.877271840808, 8585.82828343442, 11018.944905275743, 11039.668809935714, 11327.660170194908, 11610.419479026184, 10081.495925203857, 10134.949325253327, 10314.638987635397, 10347.94826025865, 10611.2041596879, 8472.279856212044, 9969.800603988024, 10480.58077676915, 10504.894951050392, 10744.194185435967, 10357.430341330997, 10537.841932371173, 10718.517666704862, 10780.838287425851, 10943.23397362209, 7791.571463569559, 8051.718189863327, 8084.514929104273, 8120.269175774209, 8134.63394147253, 9575.329726919048, 10520.473976301002, 10534.209934255116, 10679.46602669848, 10771.569137234736], "unit": "score", "name": "jquery.html", "value": 8748.369328060571}, {"lowerIsBetter": false, "replicates": [1404.5294826233212, 1533.6625942292694, 1574.8110226772783, 1775.440736168107, 1877.9624407511858, 7401.0748656417945, 7930.40521960853, 8097.716579919704, 8111.148329425399, 8118.91881081189, 7915.564643944581, 8051.838963220732, 8097.433179677425, 8098.595070246493, 8106.067802202734, 2440.0605766779827, 2472.307753828931, 2473.1714875516723, 2474.4679893822827, 2475.5296493666237, 2345.777151170636, 2370.111208296889, 2457.066314800373, 2459.9016039358403, 2461.187808023357, 2373.027058905849, 2396.161343529768, 2406.0255596483366, 2417.033186725306, 2432.84186527876, 2367.1714899785024, 2388.677528533643, 2393.533261014103, 2395.113807891074, 2435.9512809743705, 2328.4178955261186, 2330.7086614173227, 2332.731735850382, 2338.181636427254, 2355.328731311585, 8279.793005174859, 8308.750737477885, 8321.66713331466, 8322.83354332916, 8325.25072743456, 5072.188449848007, 5183.041137389558, 5189.065968125736, 5195.064888320101, 5200.843974680765, 3324.734021278316, 3349.246419555605, 3412.709919656832, 3424.298018906118, 3450.4824276358495, 1140.954361825526, 1197.1619866093681, 1201.237214368882, 1228.748106638137, 1243.0801207106767, 11790.646280611596, 11791.823122653168, 11802.881971180264, 11818.822717659243, 11830.0, 4609.377734005866, 4644.86065418038, 4649.349091127245, 4673.929891051637, 4745.620350371962, 9893.65372211969, 9903.80192396148, 9907.356021858555, 9913.45475998827, 9933.056359645821, 5071.467495912876, 5105.106606343875, 5114.71869047206, 5115.846524604268, 5116.079105760999, 4165.042040330741, 4209.158168366339, 4599.333096701027, 4649.04694537619, 4706.717596944194, 3580.7672501287334, 3593.5328407307406, 3639.6542328478754, 3643.890683279506, 3679.5952445231005, 1207.824865394513, 1257.2268055146203, 1261.9369031548385, 1275.0755702116078, 1279.6544932868073, 1429.8999070064995, 1433.1902475101742, 1433.4982756035304, 1443.8556144385686, 1449.1087980891887, 7821.843563128706, 7844.254795794391, 7876.448648594658, 7883.093444253869, 7887.723929662434, 541.5667466027242, 550.3038656100036, 551.503646717957, 558.904986152355, 559.4377650461261, 6308.337624549448, 6332.271788744261, 6336.6831658416895, 6349.6507692077375, 6370.980643097082, 9871.210303175729, 10024.699259022242, 10130.594776208869, 10131.949340253252, 10161.949190254001, 8974.596143173572, 9956.352837065568, 10086.49567521621, 10103.19174466041, 10138.645147419806, 7792.337651299589, 7805.960970195226, 7852.882206766903, 7875.133735289113, 7972.123066462685, 7560.773176804704, 7669.041369828771, 7842.098158711593, 7873.33076688465, 7907.367410607253, 8631.093735157969, 8866.556672166495, 8921.553922303729, 8937.508437035765, 9043.23132533742, 9029.954850225708, 9266.768330791785, 9303.953480232556, 9459.574319155792, 9467.242620590474, 6445.355464453704, 6561.212654481302, 6904.930950690429, 7175.712971481083, 7305.232950540117, 7537.095548534211, 7562.697492100255, 7585.658645360862, 7608.277213664693, 7610.961945190239, 7278.126624805057, 7376.225496322809, 7385.076865391826, 7427.294407031323, 7579.166291707797, 7212.819679507845, 7226.602536860523, 7326.853462930818, 7415.480916335805, 7508.887366689395, 7591.58246296459, 7668.463207575416, 7683.577403242875, 7686.46194766361, 7692.7692169235015, 7079.646017698991, 7137.179224389054, 7187.389071928945, 7189.388901943394, 7192.604406757679, 7068.081149450539, 7083.1854336749875, 7124.893126603001, 7140.607266600179, 7143.678534465857], "unit": "score", "name": "mootools.html", "value": 4720.223055419572}, {"lowerIsBetter": false, "replicates": [348.82035751587927, 358.0600922578232, 359.13627725320606, 359.60084306419867, 360.6069384371035, 5990.520758339328, 6032.215811944447, 6034.547408944325, 6039.72821223045, 6087.147799308101, 5696.857578560538, 5810.5932584719085, 5837.8832423351505, 5916.201312822767, 5979.1330257112695, 4649.418822647169, 4665.2768820832825, 4668.043051174501, 4672.135654903854, 4682.133805246023, 4462.620677242438, 4685.742284174369, 4687.273472611751, 4691.741954192518, 4696.741679207642, 4494.393256910308, 4499.437570303712, 4552.68131230814, 4557.293619488984, 4560.065186636748, 4581.977090114545, 4603.907921841578, 4608.055348653518, 4613.930791038137, 4615.769211539427, 4099.508059032905, 4106.260873042851, 4107.527634322061, 4107.938380924288, 4111.403846442249, 5564.221009058735, 5697.544196464314, 5708.286464191976, 5708.7145642718115, 5713.314402271712, 5387.730613469311, 5389.8113566025, 5401.837944861661, 5405.054115529767, 5406.270153529263, 6392.680365981729, 6394.360563943615, 6396.552241343108, 6403.7438502459845, 6404.167458230447, 4503.369528266013, 4516.909661806778, 4518.615917647004, 4519.344695019204, 4520.208963431386, 6505.121808555832, 6556.967215163893, 6614.0740296358545, 6621.304762999864, 6621.768238111691, 4645.605123564535, 4647.047355292157, 4651.790669419885, 4655.208614535537, 4658.301254811738, 4711.52884711533, 4714.245720684674, 4717.009428020086, 4720.12677654635, 4720.150372932905, 5385.946140538623, 5395.271638328853, 5399.568034557226, 5410.053240682865, 5411.134218525017, 6257.374262573706, 6359.046143078575, 6401.199850018747, 6414.1340918976275, 6415.3263907289065, 1631.4697723239995, 1669.3155806119432, 1669.4157045034142, 1670.582354411397, 1670.874684398675, 1097.8023955688052, 1117.8211486162174, 1122.1359553144034, 1123.6179698902413, 1142.3431526871916, 6210.534209934273, 6211.409916057967, 6216.533759968021, 6217.9689101555105, 6229.813105606839, 6332.271788744353, 6554.442872355808, 6577.769778057745, 6616.305287944792, 6638.137042184485, 6087.178230938855, 6148.292946311142, 6263.71813268404, 6279.372062793684, 6286.779962701284, 3507.9649203508147, 3561.6082230954576, 3563.7861728296384, 3566.98216508921, 3569.1790888095366, 6722.0, 6834.692438840264, 6840.6921688524135, 6841.350071743176, 6849.554778939353, 6794.184697836291, 6795.286494918061, 6796.422304104108, 6800.217974932847, 6802.455803535705, 6828.419584335288, 6830.453563714891, 6830.897536536956, 6833.282505336968, 6841.110655614738, 6447.323031081669, 6562.015697645392, 6609.239937407259, 6620.933790662224, 6624.436922861611, 6518.380753828379, 6540.705668244846, 6565.934340656532, 6568.770093046721, 6577.440917522064, 6877.071595334566, 6879.862402752017, 6883.621400823003, 6887.449004079562, 6893.0694356261265, 4631.791569379453, 4645.117427688729, 4660.300954856799, 4669.789859456265, 4675.625949924066, 6575.079488871466, 6765.357291057342, 6773.424258938046, 6782.932170678428, 6783.152105986752, 6458.61248325102, 6532.967335163294, 6533.705983230671, 6547.377999090078, 6550.0, 6292.370762923671, 6520.576162549419, 6521.119648847345, 6526.314736952551, 6529.575577587441, 6376.681165941592, 6382.074599183, 6410.45511131559, 6412.557174635856, 6424.903626445701, 6523.902141467976, 6542.509311801729, 6554.410103090745, 6556.377144171296, 6564.4420224281475, 6258.43674069336, 6385.776497822554, 6389.4888408926245, 6391.169148010729, 6399.200099987502], "unit": "score", "name": "prototype.html", "value": 4872.671766155138}, {"lowerIsBetter": false, "replicates": [857.8412993596187, 876.7676565710088, 917.6146018672157, 919.8850143732034, 930.2418528898943, 13093.476260949563, 13237.470501179941, 13240.999999999987, 13251.46994120234, 13273.402696878651, 13162.144460610054, 13296.069275150743, 13301.534446294381, 13328.40022199001, 13333.333333333343, 715.2275542414193, 725.9274072592725, 846.1792061700141, 864.2999170671776, 864.360373323739, 839.95380254086, 840.8528507511191, 842.3345557009955, 842.5871323051722, 843.6920524008723, 760.5360729954723, 767.57399643198, 910.2945217456459, 914.5564401265381, 914.8444764390069, 892.6652505310509, 903.5301643145593, 908.4458480326995, 909.4361495872536, 909.5179554835914, 671.0437626382386, 671.4091599392552, 671.4158681946689, 671.516508114157, 672.3411057163958, 14145.929270353685, 14446.566603001927, 14474.203918784462, 14531.128132312095, 14616.12303261797, 13935.303234838217, 13974.510892118727, 13992.30038498071, 14071.929640351835, 14337.71324573513, 11052.281601695944, 11052.834207486894, 11181.608643697431, 11420.200585958986, 11557.24877882935, 3845.1540661054523, 3845.7115716321387, 3889.6499315061496, 3900.0639846436934, 3942.0, 49998.50001499975, 50223.0, 50377.74811125958, 50400.74799626015, 51223.23165152526, 10415.218858585637, 10755.247132700635, 10756.56973721058, 10981.835272470918, 11082.944585277184, 18759.0, 18776.436706898814, 18798.96605686673, 18805.15376808047, 19627.0, 7672.079350477867, 7789.0, 7808.921910780819, 7868.72459463916, 8018.158093400109, 4301.440812694393, 4345.630621397154, 4371.322445021026, 4417.801198946056, 4595.241785105421, 3221.3074189049466, 3228.047725920859, 3229.0, 3260.6413294537583, 3264.1186879542392, 659.0707102984768, 659.8251463362213, 684.090160087083, 684.2370756606348, 684.4524380495584, 685.5543896467236, 702.9578225306498, 704.8378872859168, 720.4884531982246, 728.7667946257146, 8909.376343655882, 9085.682001129928, 9154.450732956044, 9216.216621587106, 9282.767930801783, 600.1172414478318, 602.5119653081018, 678.9781379024635, 748.1994266135183, 750.9286617771303, 4328.8484903028875, 4483.327500874895, 4483.453208643013, 4528.388667529907, 4569.954300456953, 54673.0, 55019.72490137524, 55048.0, 55343.0, 55360.0, 49118.26322605164, 49650.255246171335, 49784.25323620148, 50355.24467132996, 50642.493575064516, 44532.77733611311, 44944.77527612406, 45007.32489012668, 45896.770516147204, 46061.76969115133, 8890.86663700058, 9010.684626038057, 9149.862752058858, 9168.770780730536, 9385.671501497414, 33354.83322583372, 33837.830810846775, 33992.83003584966, 34228.48657270093, 34312.48531271983, 38222.0, 50089.74955125201, 51928.22107668313, 52080.21879671732, 52293.0, 12023.81964270554, 12075.215111018108, 12296.938515307365, 12457.875421245672, 12491.625251242478, 12957.22256664603, 13179.275139867015, 13195.20828750278, 13277.269750163834, 13534.255615941218, 12121.333326667118, 12290.139690221598, 12633.494660213488, 12838.29389383593, 13050.477980880658, 12803.167794093353, 12833.294168820805, 12852.55016074433, 12867.742645147231, 13234.205947643171, 12748.362581870684, 12861.807072894102, 12879.871201287868, 12926.224426534438, 13107.475700971854, 10157.136643385395, 10317.845232321371, 10773.676789696321, 10799.40603266828, 10905.618303359344, 10547.103496202908, 10636.202284828823, 10731.785364292826, 10790.514426850968, 10828.512716927915], "unit": "score", "name": "yui.html", "value": 6701.304405380864}], "name": "dromaeo_css", "value": 7185.32032432427}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 105.0, 112.0, 111.0, 110.0, 101.0, 110.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 109.0}, {"lowerIsBetter": true, "replicates": [159.0, 123.0, 148.0, 155.0, 124.0, 159.0, 147.0, 129.0, 168.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [161.0, 159.0, 161.0, 161.0, 162.0, 161.0, 158.0, 161.0, 162.0, 160.0], "unit": "score", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [77.0, 77.0, 73.0, 147.0, 74.0, 77.0, 75.0, 74.0, 146.0, 73.0], "unit": "score", "name": "audio-fft", "value": 89.3}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 136.0, 94.0, 95.0, 95.0, 141.0, 94.0, 96.0, 145.0], "unit": "score", "name": "audio-oscillator", "value": 108.5}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 108.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 145.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 57.0, 54.0, 53.0, 56.0, 58.0, 55.0, 53.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.0}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 64.0, 65.0, 64.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.7}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 86.0, 79.0, 79.0, 79.0, 80.0, 79.0, 79.0, 79.0], "unit": "score", "name": "stanford-crypto-aes", "value": 79.5}, {"lowerIsBetter": true, "replicates": [115.0, 129.0, 113.0, 111.0, 111.0, 112.0, 111.0, 110.0, 108.0, 110.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 113.0}, {"lowerIsBetter": true, "replicates": [131.0, 130.0, 136.0, 130.0, 131.0, 134.0, 132.0, 132.0, 131.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 132.0}, {"lowerIsBetter": true, "replicates": [64.0, 67.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 62.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 64.7}], "name": "kraken", "value": 1470.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.61112962345885], "unit": "score", "name": "Richards", "value": 211.88260400000004}, {"lowerIsBetter": false, "replicates": [169.80811682798438], "unit": "score", "name": "DeltaBlue", "value": 389.3689020000002}, {"lowerIsBetter": false, "replicates": [281.0567734682406], "unit": "score", "name": "Encrypt", "value": 947.0719979999998}, {"lowerIsBetter": false, "replicates": [5229.166666666667], "unit": "score", "name": "Decrypt", "value": 50.90313944223106}, {"lowerIsBetter": false, "replicates": [952.3809523809524], "unit": "score", "name": "RayTrace", "value": 776.9884500000003}, {"lowerIsBetter": false, "replicates": [549.7526113249038], "unit": "score", "name": "Earley", "value": 1212.296197}, {"lowerIsBetter": false, "replicates": [10578.947368421053], "unit": "score", "name": "Boyer", "value": 62.99899004975127}, {"lowerIsBetter": false, "replicates": [40740.0], "unit": "score", "name": "RegExp", "value": 22.360947471772207}, {"lowerIsBetter": false, "replicates": [498.00796812749], "unit": "score", "name": "Splay", "value": 163.63392800000005}, {"lowerIsBetter": false, "replicates": [4817.307692307692], "unit": "score", "name": "NavierStokes", "value": 308.055888223553}], "name": "v8_7", "value": 22121.891643582603}]} 09:27:17 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,181,35]]", "result": "22121.89"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,181,35]]", "result": "1470.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,181,35]]", "result": "7185.32"}}} 09:27:17 INFO - Return code: 0 09:27:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:27:17 INFO - # TBPL SUCCESS # 09:27:17 INFO - Running post-action listener: _resource_record_post_action 09:27:17 INFO - Running post-run listener: _resource_record_post_run 09:27:18 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 4096; Write bytes: 464601088; Read time: 16; Write time: 372952 09:27:18 INFO - install - Wall time: 13s; CPU: 14.0%; Read bytes: 0; Write bytes: 234283008; Read time: 0; Write time: 258980 09:27:18 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 4096; Write bytes: 229879808; Read time: 16; Write time: 113944 09:27:18 INFO - Running post-run listener: _upload_blobber_files 09:27:18 INFO - Blob upload gear active. 09:27:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:18 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 09:27:18 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', 'Date-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:27:18 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 Date-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:27:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:27:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:27:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:20 INFO - (blobuploader) - INFO - Done attempting. 09:27:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:27:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:27:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:20 INFO - (blobuploader) - INFO - Done attempting. 09:27:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:21 INFO - Return code: 0 09:27:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/9cb3a2894f0501ffdf909ab976cb1cd01f79c1b07fc2bab5f208b9244e8c8bab7a7a5bd980a19ec3869c22e7418f991b1ac56ef33b1331d209c0db9623213a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/11f0185ebbef997a1227b0040c0c004bcb3aa88c73ec420bd77f6cce9a9fedff12e450c05f271d5d0c705c468d88d02504ef7f6841e0400b2b20513e533b9d2d"} 09:27:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:21 INFO - Contents: 09:27:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/9cb3a2894f0501ffdf909ab976cb1cd01f79c1b07fc2bab5f208b9244e8c8bab7a7a5bd980a19ec3869c22e7418f991b1ac56ef33b1331d209c0db9623213a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/11f0185ebbef997a1227b0040c0c004bcb3aa88c73ec420bd77f6cce9a9fedff12e450c05f271d5d0c705c468d88d02504ef7f6841e0400b2b20513e533b9d2d"} 09:27:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:21 INFO - Copying logs to upload dir... 09:27:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.853853 ========= master_lag: 8.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-03-03 09:27:29.065398) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-03 09:27:29.068946) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/9cb3a2894f0501ffdf909ab976cb1cd01f79c1b07fc2bab5f208b9244e8c8bab7a7a5bd980a19ec3869c22e7418f991b1ac56ef33b1331d209c0db9623213a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/11f0185ebbef997a1227b0040c0c004bcb3aa88c73ec420bd77f6cce9a9fedff12e450c05f271d5d0c705c468d88d02504ef7f6841e0400b2b20513e533b9d2d"} build_url:https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/D29x1Bo2RqujaKv0z6zzEg/artifacts/public/build/firefox-46.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/9cb3a2894f0501ffdf909ab976cb1cd01f79c1b07fc2bab5f208b9244e8c8bab7a7a5bd980a19ec3869c22e7418f991b1ac56ef33b1331d209c0db9623213a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Date-Non-PGO/sha512/11f0185ebbef997a1227b0040c0c004bcb3aa88c73ec420bd77f6cce9a9fedff12e450c05f271d5d0c705c468d88d02504ef7f6841e0400b2b20513e533b9d2d"}' ========= master_lag: 0.17 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-03 09:27:29.245263) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:27:29.245573) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1457024778.585726-19870688 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 09:27:29.260820) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-03 09:27:29.261081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-03 09:27:29.261343) ========= ========= Total master_lag: 8.65 =========