builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1480300604.24 results: success (0) buildid: 20161127172258 builduid: b2f4ffc267e648e09d6c39face58fbeb revision: d263165be1c99d4181bdc3275e697d4bb85bafcc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.242912) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.243281) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.243601) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.260211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.260463) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-27 18:36:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 199K=0.06s 2016-11-27 18:36:45 (199 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.201675 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.475127) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.475408) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014906 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.525023) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:44.525345) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev d263165be1c99d4181bdc3275e697d4bb85bafcc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev d263165be1c99d4181bdc3275e697d4bb85bafcc --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=56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-27 18:36:45,438 truncating revision to first 12 chars 2016-11-27 18:36:45,438 Setting DEBUG logging. 2016-11-27 18:36:45,439 attempt 1/10 2016-11-27 18:36:45,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/d263165be1c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-27 18:36:45,919 unpacking tar archive at: graphics-d263165be1c9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.814052 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:45.354269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:45.354536) ========= script_repo_revision: d263165be1c99d4181bdc3275e697d4bb85bafcc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:45.354900) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:45.355149) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 18:36:45.365313) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-11-27 18:36:45.365561) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:36:46 INFO - MultiFileLogger online at 20161127 18:36:46 in /builds/slave/test 18:36:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 18:36:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:36:46 INFO - {'append_to_log': False, 18:36:46 INFO - 'base_work_dir': '/builds/slave/test', 18:36:46 INFO - 'blob_upload_branch': 'Graphics', 18:36:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:36:46 INFO - 'branch': 'Graphics', 18:36:46 INFO - 'buildbot_json_path': 'buildprops.json', 18:36:46 INFO - 'config_files': ('talos/linux_config.py',), 18:36:46 INFO - 'default_actions': ('clobber', 18:36:46 INFO - 'read-buildbot-config', 18:36:46 INFO - 'download-and-extract', 18:36:46 INFO - 'populate-webroot', 18:36:46 INFO - 'create-virtualenv', 18:36:46 INFO - 'install', 18:36:46 INFO - 'run-tests'), 18:36:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:36:46 INFO - 'download_minidump_stackwalk': True, 18:36:46 INFO - 'download_symbols': 'ondemand', 18:36:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:36:46 INFO - 'tooltool.py': '/tools/tooltool.py', 18:36:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:36:46 INFO - '/tools/misc-python/virtualenv.py')}, 18:36:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:36:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:36:46 INFO - 'installer_path': 'installer.exe', 18:36:46 INFO - 'log_level': 'info', 18:36:46 INFO - 'log_name': 'talos', 18:36:46 INFO - 'log_to_console': True, 18:36:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:36:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:36:46 INFO - 'opt_config_files': (), 18:36:46 INFO - 'pip_index': False, 18:36:46 INFO - 'sps_profile': False, 18:36:46 INFO - 'sps_profile_interval': 0, 18:36:46 INFO - 'suite': 'dromaeojs', 18:36:46 INFO - 'system_bits': '32', 18:36:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:36:46 INFO - 'title': 'talos-linux64-ix-037', 18:36:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:36:46 INFO - 'use_talos_json': True, 18:36:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:36:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:36:46 INFO - 'work_dir': 'build'} 18:36:46 INFO - [mozharness: 2016-11-28 02:36:46.370037Z] Running clobber step. 18:36:46 INFO - Running pre-action listener: _resource_record_pre_action 18:36:46 INFO - Running main action method: clobber 18:36:46 INFO - rmtree: /builds/slave/test/build 18:36:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:36:46 INFO - Running post-action listener: _resource_record_post_action 18:36:46 INFO - [mozharness: 2016-11-28 02:36:46.831269Z] Finished clobber step (success) 18:36:46 INFO - [mozharness: 2016-11-28 02:36:46.831368Z] Running read-buildbot-config step. 18:36:46 INFO - Running pre-action listener: _resource_record_pre_action 18:36:46 INFO - Running main action method: read_buildbot_config 18:36:46 INFO - Using buildbot properties: 18:36:46 INFO - { 18:36:46 INFO - "project": "", 18:36:46 INFO - "product": "firefox", 18:36:46 INFO - "script_repo_revision": "default", 18:36:46 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 18:36:46 INFO - "repository": "", 18:36:46 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 18:36:46 INFO - "buildid": "20161127172258", 18:36:46 INFO - "pgo_build": "False", 18:36:46 INFO - "basedir": "/builds/slave/test", 18:36:46 INFO - "buildnumber": 24, 18:36:46 INFO - "slavename": "talos-linux64-ix-037", 18:36:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:36:46 INFO - "platform": "ubuntu64_hw", 18:36:46 INFO - "scheduler": "tests-graphics-linux64-talos", 18:36:46 INFO - "branch": "graphics", 18:36:46 INFO - "repo_path": "projects/graphics", 18:36:46 INFO - "revision": "d263165be1c99d4181bdc3275e697d4bb85bafcc", 18:36:46 INFO - "stage_platform": "linux64", 18:36:46 INFO - "builduid": "b2f4ffc267e648e09d6c39face58fbeb", 18:36:46 INFO - "slavebuilddir": "test" 18:36:46 INFO - } 18:36:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:36:46 INFO - Running post-action listener: _resource_record_post_action 18:36:46 INFO - [mozharness: 2016-11-28 02:36:46.833795Z] Finished read-buildbot-config step (success) 18:36:46 INFO - [mozharness: 2016-11-28 02:36:46.833883Z] Running download-and-extract step. 18:36:46 INFO - Running pre-action listener: _resource_record_pre_action 18:36:46 INFO - Running main action method: download_and_extract 18:36:46 INFO - mkdir: /builds/slave/test/build/tests 18:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:46 INFO - trying https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:36:46 INFO - Downloading https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:36:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:36:47 INFO - Downloaded 1361 bytes. 18:36:47 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:36:47 INFO - Using the following test package requirements: 18:36:47 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:36:47 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:36:47 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:36:47 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'jsshell-linux-x86_64.zip'], 18:36:47 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:36:47 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:36:47 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:36:47 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:36:47 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:36:47 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:36:47 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:36:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:36:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:36:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:36:48 INFO - Http code: 200 18:36:48 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 18:36:48 INFO - x-amz-cf-id: AGFWNyTwQRvbVPZGKGKRTqayvFh139fTfrz_SgbtTq9gI42ahwTAYA== 18:36:48 INFO - x-amz-version-id: tGSogBF16.QZdYw3cEyeXAimdHEoL1Cz 18:36:49 INFO - Expected file size: 20202013 18:36:49 INFO - Obtained file size: 20202013 18:36:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:36:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:36:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:36:53 INFO - Fetch https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:36:53 INFO - Http code: 200 18:36:53 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 18:36:53 INFO - x-amz-cf-id: C-_YYe0gThOZuEubx-YZlUAUh2j9fvlq74mqSUHTTL2vXQznIEFREQ== 18:36:53 INFO - x-amz-version-id: tGSogBF16.QZdYw3cEyeXAimdHEoL1Cz 18:36:53 INFO - Expected file size: 20202013 18:36:53 INFO - Obtained file size: 20202013 18:36:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:36:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:36:58 INFO - Fetch https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:36:59 INFO - Http code: 200 18:36:59 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 18:36:59 INFO - x-amz-cf-id: vZQBHc7FUEl181fdDVD9tnTHW7QLUjeciwH20igs-jsjBnirsnea6Q== 18:36:59 INFO - x-amz-version-id: S4o1CSm3ke2rpp7RFNggYsta6SsR4o2G 18:36:59 INFO - Expected file size: 14409506 18:36:59 INFO - Obtained file size: 14409506 18:37:00 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:00 INFO - trying https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:37:00 INFO - Downloading https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:37:03 INFO - Downloaded 55604829 bytes. 18:37:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:37:03 INFO - mkdir: /builds/slave/test/properties 18:37:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:37:03 INFO - Writing to file /builds/slave/test/properties/build_url 18:37:03 INFO - Contents: 18:37:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:37:04 INFO - Running post-action listener: _resource_record_post_action 18:37:04 INFO - Running post-action listener: set_extra_try_arguments 18:37:04 INFO - [mozharness: 2016-11-28 02:37:04.596193Z] Finished download-and-extract step (success) 18:37:04 INFO - [mozharness: 2016-11-28 02:37:04.596418Z] Running populate-webroot step. 18:37:04 INFO - Running pre-action listener: _resource_record_pre_action 18:37:04 INFO - Running main action method: populate_webroot 18:37:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:37:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:37:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:37:04 INFO - '--tppagecycles', 18:37:04 INFO - '7'], 18:37:04 INFO - 'tests': ['tsvgm']}}, 18:37:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:37:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:37:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:37:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:37:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:37:04 INFO - 'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:37:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:37:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:37:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:37:04 INFO - 'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['damp', 'tps']}, 18:37:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:37:04 INFO - 'tests': ['damp', 'tps']}, 18:37:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['dromaeo_dom']}, 18:37:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:37:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['basic_compositor_video']}, 18:37:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:37:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['a11yr', 18:37:04 INFO - 'ts_paint', 18:37:04 INFO - 'tpaint', 18:37:04 INFO - 'sessionrestore', 18:37:04 INFO - 'sessionrestore_no_auto_restore', 18:37:04 INFO - 'tabpaint']}, 18:37:04 INFO - 'other-e10s': {'tests': ['a11yr', 18:37:04 INFO - 'ts_paint', 18:37:04 INFO - 'tpaint', 18:37:04 INFO - 'sessionrestore', 18:37:04 INFO - 'sessionrestore_no_auto_restore', 18:37:04 INFO - 'tabpaint']}, 18:37:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:37:04 INFO - 'ts_paint', 18:37:04 INFO - 'tpaint', 18:37:04 INFO - 'sessionrestore', 18:37:04 INFO - 'sessionrestore_no_auto_restore', 18:37:04 INFO - 'tabpaint']}, 18:37:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:37:04 INFO - 'ts_paint', 18:37:04 INFO - 'tpaint', 18:37:04 INFO - 'sessionrestore', 18:37:04 INFO - 'sessionrestore_no_auto_restore', 18:37:04 INFO - 'tabpaint']}, 18:37:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['a11yr', 18:37:04 INFO - 'ts_paint', 18:37:04 INFO - 'tpaint', 18:37:04 INFO - 'sessionrestore', 18:37:04 INFO - 'sessionrestore_no_auto_restore', 18:37:04 INFO - 'tabpaint']}, 18:37:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['a11yr', 18:37:04 INFO - 'ts_paint', 18:37:04 INFO - 'tpaint', 18:37:04 INFO - 'sessionrestore', 18:37:04 INFO - 'sessionrestore_no_auto_restore', 18:37:04 INFO - 'tabpaint']}, 18:37:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['tsvgx', 18:37:04 INFO - 'tsvgr_opacity', 18:37:04 INFO - 'tart', 18:37:04 INFO - 'tscrollx', 18:37:04 INFO - 'cart']}, 18:37:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:37:04 INFO - 'tsvgr_opacity', 18:37:04 INFO - 'tart', 18:37:04 INFO - 'tscrollx', 18:37:04 INFO - 'cart']}, 18:37:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:37:04 INFO - 'talos_options': ['--disable-e10s'], 18:37:04 INFO - 'tests': ['tp5o']}, 18:37:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:37:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:37:04 INFO - 'talos_options': ['--disable-e10s', 18:37:04 INFO - '--xperf_path', 18:37:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:37:04 INFO - 'tests': ['tp5n']}, 18:37:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:37:04 INFO - 'talos_options': ['--xperf_path', 18:37:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:37:04 INFO - 'tests': ['tp5n']}}, 18:37:04 INFO - 'talos.zip': {'path': '', 18:37:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:37:04 INFO - Running post-action listener: _resource_record_post_action 18:37:04 INFO - [mozharness: 2016-11-28 02:37:04.622173Z] Finished populate-webroot step (success) 18:37:04 INFO - [mozharness: 2016-11-28 02:37:04.622260Z] Running create-virtualenv step. 18:37:04 INFO - Running pre-action listener: _resource_record_pre_action 18:37:04 INFO - Running main action method: create_virtualenv 18:37:04 INFO - Creating virtualenv /builds/slave/test/build/venv 18:37:04 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 18:37:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:37:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:37:04 INFO - Using real prefix '/usr' 18:37:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:37:05 INFO - Installing distribute.............................................................................................................................................................................................done. 18:37:07 INFO - Installing pip.................done. 18:37:07 INFO - Return code: 0 18:37:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:37:07 INFO - Reading from file tmpfile_stdout 18:37:07 INFO - Output received: 18:37:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:37:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:07 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 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:07 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 18:37:07 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 18:37:07 INFO - Using env: {'DISPLAY': ':0', 18:37:07 INFO - 'HOME': '/home/cltbld', 18:37:07 INFO - 'LANG': 'en_US.UTF-8', 18:37:07 INFO - 'LANGUAGE': 'en_US:en', 18:37:07 INFO - 'LOGNAME': 'cltbld', 18:37:07 INFO - 'MAIL': '/var/mail/cltbld', 18:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:07 INFO - 'MOZ_NO_REMOTE': '1', 18:37:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:07 INFO - 'NO_EM_RESTART': '1', 18:37:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:07 INFO - 'PWD': '/builds/slave/test', 18:37:07 INFO - 'SHELL': '/bin/bash', 18:37:07 INFO - 'SHLVL': '1', 18:37:07 INFO - 'TERM': 'linux', 18:37:07 INFO - 'TMOUT': '86400', 18:37:07 INFO - 'USER': 'cltbld', 18:37:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:07 INFO - '_': '/tools/buildbot/bin/python'} 18:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:07 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 18:37:07 INFO - Cleaning up... 18:37:07 INFO - Return code: 0 18:37:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:37:07 INFO - Using env: {'DISPLAY': ':0', 18:37:07 INFO - 'HOME': '/home/cltbld', 18:37:07 INFO - 'LANG': 'en_US.UTF-8', 18:37:07 INFO - 'LANGUAGE': 'en_US:en', 18:37:07 INFO - 'LOGNAME': 'cltbld', 18:37:07 INFO - 'MAIL': '/var/mail/cltbld', 18:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:07 INFO - 'MOZ_NO_REMOTE': '1', 18:37:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:07 INFO - 'NO_EM_RESTART': '1', 18:37:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:07 INFO - 'PWD': '/builds/slave/test', 18:37:07 INFO - 'SHELL': '/bin/bash', 18:37:07 INFO - 'SHLVL': '1', 18:37:07 INFO - 'TERM': 'linux', 18:37:07 INFO - 'TMOUT': '86400', 18:37:07 INFO - 'USER': 'cltbld', 18:37:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:07 INFO - '_': '/tools/buildbot/bin/python'} 18:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:07 INFO - Downloading/unpacking psutil>=3.1.1 18:37:07 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 18:37:07 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 18:37:07 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 18:37:07 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 18:37:07 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 18:37:07 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 18:37:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:37:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:37:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:37:09 INFO - Installing collected packages: psutil 18:37:09 INFO - Running setup.py install for psutil 18:37:09 INFO - building 'psutil._psutil_linux' extension 18:37:09 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 18:37:10 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 18:37:10 INFO - building 'psutil._psutil_posix' extension 18:37:10 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 18:37:10 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 18:37:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:37:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:37:10 INFO - Successfully installed psutil 18:37:10 INFO - Cleaning up... 18:37:10 INFO - Return code: 0 18:37:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:37:10 INFO - Using env: {'DISPLAY': ':0', 18:37:10 INFO - 'HOME': '/home/cltbld', 18:37:10 INFO - 'LANG': 'en_US.UTF-8', 18:37:10 INFO - 'LANGUAGE': 'en_US:en', 18:37:10 INFO - 'LOGNAME': 'cltbld', 18:37:10 INFO - 'MAIL': '/var/mail/cltbld', 18:37:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:10 INFO - 'MOZ_NO_REMOTE': '1', 18:37:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:10 INFO - 'NO_EM_RESTART': '1', 18:37:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:10 INFO - 'PWD': '/builds/slave/test', 18:37:10 INFO - 'SHELL': '/bin/bash', 18:37:10 INFO - 'SHLVL': '1', 18:37:10 INFO - 'TERM': 'linux', 18:37:10 INFO - 'TMOUT': '86400', 18:37:10 INFO - 'USER': 'cltbld', 18:37:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:10 INFO - '_': '/tools/buildbot/bin/python'} 18:37:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:37:10 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 18:37:10 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 18:37:10 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 18:37:10 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 18:37:10 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 18:37:10 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 18:37:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:37:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:37:12 INFO - Installing collected packages: mozsystemmonitor 18:37:12 INFO - Running setup.py install for mozsystemmonitor 18:37:12 INFO - Successfully installed mozsystemmonitor 18:37:12 INFO - Cleaning up... 18:37:12 INFO - Return code: 0 18:37:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:37:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:37:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:37:12 INFO - Using env: {'DISPLAY': ':0', 18:37:12 INFO - 'HOME': '/home/cltbld', 18:37:12 INFO - 'LANG': 'en_US.UTF-8', 18:37:12 INFO - 'LANGUAGE': 'en_US:en', 18:37:12 INFO - 'LOGNAME': 'cltbld', 18:37:12 INFO - 'MAIL': '/var/mail/cltbld', 18:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:12 INFO - 'MOZ_NO_REMOTE': '1', 18:37:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:12 INFO - 'NO_EM_RESTART': '1', 18:37:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:12 INFO - 'PWD': '/builds/slave/test', 18:37:12 INFO - 'SHELL': '/bin/bash', 18:37:12 INFO - 'SHLVL': '1', 18:37:12 INFO - 'TERM': 'linux', 18:37:12 INFO - 'TMOUT': '86400', 18:37:12 INFO - 'USER': 'cltbld', 18:37:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:12 INFO - '_': '/tools/buildbot/bin/python'} 18:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:13 INFO - Downloading/unpacking jsonschema==2.5.1 18:37:13 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 18:37:13 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 18:37:13 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 18:37:13 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 18:37:13 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 18:37:13 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 18:37:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:37:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:37:14 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 18:37:14 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 18:37:14 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 18:37:14 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 18:37:14 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 18:37:14 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 18:37:15 INFO - Downloading functools32-3.2.3-2.tar.gz 18:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:37:15 INFO - warning: no files found matching '*.txt' 18:37:15 INFO - no previously-included directories found matching 'build' 18:37:15 INFO - no previously-included directories found matching 'dist' 18:37:15 INFO - no previously-included directories found matching '.git*' 18:37:15 INFO - Installing collected packages: jsonschema, functools32 18:37:15 INFO - Running setup.py install for functools32 18:37:15 INFO - warning: no files found matching '*.txt' 18:37:15 INFO - no previously-included directories found matching 'build' 18:37:15 INFO - no previously-included directories found matching 'dist' 18:37:15 INFO - no previously-included directories found matching '.git*' 18:37:15 INFO - Successfully installed jsonschema functools32 18:37:15 INFO - Cleaning up... 18:37:15 INFO - Return code: 0 18:37:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:37:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:37:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:37:15 INFO - Using env: {'DISPLAY': ':0', 18:37:15 INFO - 'HOME': '/home/cltbld', 18:37:15 INFO - 'LANG': 'en_US.UTF-8', 18:37:15 INFO - 'LANGUAGE': 'en_US:en', 18:37:15 INFO - 'LOGNAME': 'cltbld', 18:37:15 INFO - 'MAIL': '/var/mail/cltbld', 18:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:15 INFO - 'MOZ_NO_REMOTE': '1', 18:37:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:15 INFO - 'NO_EM_RESTART': '1', 18:37:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:15 INFO - 'PWD': '/builds/slave/test', 18:37:15 INFO - 'SHELL': '/bin/bash', 18:37:15 INFO - 'SHLVL': '1', 18:37:15 INFO - 'TERM': 'linux', 18:37:15 INFO - 'TMOUT': '86400', 18:37:15 INFO - 'USER': 'cltbld', 18:37:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:15 INFO - '_': '/tools/buildbot/bin/python'} 18:37:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:37:15 INFO - Cleaning up... 18:37:15 INFO - Return code: 0 18:37:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:37:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:15 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 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:15 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 18:37:15 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 18:37:15 INFO - Using env: {'DISPLAY': ':0', 18:37:15 INFO - 'HOME': '/home/cltbld', 18:37:15 INFO - 'LANG': 'en_US.UTF-8', 18:37:15 INFO - 'LANGUAGE': 'en_US:en', 18:37:15 INFO - 'LOGNAME': 'cltbld', 18:37:15 INFO - 'MAIL': '/var/mail/cltbld', 18:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:15 INFO - 'MOZ_NO_REMOTE': '1', 18:37:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:15 INFO - 'NO_EM_RESTART': '1', 18:37:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:15 INFO - 'PWD': '/builds/slave/test', 18:37:15 INFO - 'SHELL': '/bin/bash', 18:37:15 INFO - 'SHLVL': '1', 18:37:15 INFO - 'TERM': 'linux', 18:37:15 INFO - 'TMOUT': '86400', 18:37:15 INFO - 'USER': 'cltbld', 18:37:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:15 INFO - '_': '/tools/buildbot/bin/python'} 18:37:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:15 INFO - Downloading/unpacking blobuploader==1.2.4 18:37:15 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 18:37:15 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 18:37:15 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 18:37:15 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 18:37:15 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 18:37:15 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 18:37:17 INFO - Downloading blobuploader-1.2.4.tar.gz 18:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:37:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:37:17 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 18:37:17 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 18:37:17 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 18:37:17 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 18:37:17 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 18:37:17 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 18:37:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:37:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:37:18 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 18:37:18 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 18:37:18 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 18:37:18 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 18:37:18 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 18:37:18 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 18:37:18 INFO - Downloading docopt-0.6.1.tar.gz 18:37:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:37:18 INFO - Installing collected packages: blobuploader, requests, docopt 18:37:18 INFO - Running setup.py install for blobuploader 18:37:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:37:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:37:18 INFO - Running setup.py install for requests 18:37:19 INFO - Running setup.py install for docopt 18:37:19 INFO - Successfully installed blobuploader requests docopt 18:37:19 INFO - Cleaning up... 18:37:19 INFO - Return code: 0 18:37:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:37:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:19 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 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:19 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 18:37:19 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 18:37:19 INFO - Using env: {'DISPLAY': ':0', 18:37:19 INFO - 'HOME': '/home/cltbld', 18:37:19 INFO - 'LANG': 'en_US.UTF-8', 18:37:19 INFO - 'LANGUAGE': 'en_US:en', 18:37:19 INFO - 'LOGNAME': 'cltbld', 18:37:19 INFO - 'MAIL': '/var/mail/cltbld', 18:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:19 INFO - 'MOZ_NO_REMOTE': '1', 18:37:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:19 INFO - 'NO_EM_RESTART': '1', 18:37:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:19 INFO - 'PWD': '/builds/slave/test', 18:37:19 INFO - 'SHELL': '/bin/bash', 18:37:19 INFO - 'SHLVL': '1', 18:37:19 INFO - 'TERM': 'linux', 18:37:19 INFO - 'TMOUT': '86400', 18:37:19 INFO - 'USER': 'cltbld', 18:37:19 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:19 INFO - '_': '/tools/buildbot/bin/python'} 18:37:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:37:19 INFO - Running setup.py (path:/tmp/pip-BLnfxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:37:19 INFO - Running setup.py (path:/tmp/pip-Trjtza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:37:19 INFO - Running setup.py (path:/tmp/pip-KsiEbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:37:19 INFO - Running setup.py (path:/tmp/pip-XlqtdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:37:19 INFO - Running setup.py (path:/tmp/pip-i66AHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:37:19 INFO - Running setup.py (path:/tmp/pip-zOxmjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:37:19 INFO - Running setup.py (path:/tmp/pip-dRlSRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:37:19 INFO - Running setup.py (path:/tmp/pip-IKDXGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:37:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:37:20 INFO - Running setup.py (path:/tmp/pip-LnZw5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:37:20 INFO - Running setup.py (path:/tmp/pip-dwY62z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:37:20 INFO - Running setup.py (path:/tmp/pip-4EtIKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:37:20 INFO - Running setup.py (path:/tmp/pip-cGAZLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:37:20 INFO - Running setup.py (path:/tmp/pip-g_KSUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:37:20 INFO - Running setup.py (path:/tmp/pip-Q2U0pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:20 INFO - Running setup.py (path:/tmp/pip-aQ3xkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:37:20 INFO - Running setup.py (path:/tmp/pip-6ApqLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:37:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:37:20 INFO - Running setup.py (path:/tmp/pip-uRGe73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:37:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:37:20 INFO - Running setup.py install for manifestparser 18:37:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:37:20 INFO - Running setup.py install for mozcrash 18:37:20 INFO - Running setup.py install for mozdebug 18:37:20 INFO - Running setup.py install for mozdevice 18:37:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Running setup.py install for mozfile 18:37:21 INFO - Running setup.py install for mozhttpd 18:37:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Running setup.py install for mozinfo 18:37:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Running setup.py install for mozInstall 18:37:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Running setup.py install for mozleak 18:37:21 INFO - Running setup.py install for mozlog 18:37:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Running setup.py install for moznetwork 18:37:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Running setup.py install for mozprocess 18:37:21 INFO - Running setup.py install for mozprofile 18:37:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:37:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:37:22 INFO - Running setup.py install for mozrunner 18:37:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:37:22 INFO - Running setup.py install for mozscreenshot 18:37:22 INFO - Running setup.py install for moztest 18:37:22 INFO - Running setup.py install for mozversion 18:37:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:37:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:37:22 INFO - Cleaning up... 18:37:22 INFO - Return code: 0 18:37:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:22 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 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:22 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 18:37:22 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 18:37:22 INFO - Using env: {'DISPLAY': ':0', 18:37:22 INFO - 'HOME': '/home/cltbld', 18:37:22 INFO - 'LANG': 'en_US.UTF-8', 18:37:22 INFO - 'LANGUAGE': 'en_US:en', 18:37:22 INFO - 'LOGNAME': 'cltbld', 18:37:22 INFO - 'MAIL': '/var/mail/cltbld', 18:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:22 INFO - 'MOZ_NO_REMOTE': '1', 18:37:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:22 INFO - 'NO_EM_RESTART': '1', 18:37:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:22 INFO - 'PWD': '/builds/slave/test', 18:37:22 INFO - 'SHELL': '/bin/bash', 18:37:22 INFO - 'SHLVL': '1', 18:37:22 INFO - 'TERM': 'linux', 18:37:22 INFO - 'TMOUT': '86400', 18:37:22 INFO - 'USER': 'cltbld', 18:37:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:22 INFO - '_': '/tools/buildbot/bin/python'} 18:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:37:22 INFO - Running setup.py (path:/tmp/pip-5wOIY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:37:22 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)) 18:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:37:22 INFO - Running setup.py (path:/tmp/pip-KvR3n1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:37:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 18:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:37:22 INFO - Running setup.py (path:/tmp/pip-KUk02C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:37:22 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)) 18:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:37:22 INFO - Running setup.py (path:/tmp/pip-VfTI1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:37:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 18:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:37:22 INFO - Running setup.py (path:/tmp/pip-zW9xCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:37:23 INFO - Running setup.py (path:/tmp/pip-avIqUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:37:23 INFO - Running setup.py (path:/tmp/pip-p7K4nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:37:23 INFO - Running setup.py (path:/tmp/pip-8Ny3lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:37:23 INFO - Running setup.py (path:/tmp/pip-IhaCRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:37:23 INFO - Running setup.py (path:/tmp/pip-JPLmid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:37:23 INFO - Running setup.py (path:/tmp/pip-oIxqot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:37:23 INFO - Running setup.py (path:/tmp/pip-2MY8gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:37:23 INFO - Running setup.py (path:/tmp/pip-lU0PtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:37:23 INFO - Running setup.py (path:/tmp/pip-S8mLKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:23 INFO - Running setup.py (path:/tmp/pip-2sVZWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:37:23 INFO - Running setup.py (path:/tmp/pip-H3gJJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:37:23 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)) 18:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:37:23 INFO - Running setup.py (path:/tmp/pip-uZqcSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:37:23 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)) 18:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:37:23 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)) 18:37:23 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:37:23 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:37:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:25 INFO - Downloading blessings-1.6.tar.gz 18:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:37:25 INFO - Installing collected packages: blessings 18:37:25 INFO - Running setup.py install for blessings 18:37:26 INFO - Successfully installed blessings 18:37:26 INFO - Cleaning up... 18:37:26 INFO - Return code: 0 18:37:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:37:26 INFO - Reading from file tmpfile_stdout 18:37:26 INFO - Current package versions: 18:37:26 INFO - argparse == 1.2.1 18:37:26 INFO - blessings == 1.6 18:37:26 INFO - blobuploader == 1.2.4 18:37:26 INFO - docopt == 0.6.1 18:37:26 INFO - functools32 == 3.2.3-2 18:37:26 INFO - jsonschema == 2.5.1 18:37:26 INFO - manifestparser == 1.1 18:37:26 INFO - mozInstall == 1.12 18:37:26 INFO - mozcrash == 1.0 18:37:26 INFO - mozdebug == 0.1 18:37:26 INFO - mozdevice == 0.49 18:37:26 INFO - mozfile == 1.2 18:37:26 INFO - mozhttpd == 0.7 18:37:26 INFO - mozinfo == 0.9 18:37:26 INFO - mozleak == 0.1 18:37:26 INFO - mozlog == 3.4 18:37:26 INFO - moznetwork == 0.27 18:37:26 INFO - mozprocess == 0.23 18:37:26 INFO - mozprofile == 0.28 18:37:26 INFO - mozrunner == 6.13 18:37:26 INFO - mozscreenshot == 0.1 18:37:26 INFO - mozsystemmonitor == 0.3 18:37:26 INFO - moztest == 0.7 18:37:26 INFO - mozversion == 1.4 18:37:26 INFO - psutil == 3.1.1 18:37:26 INFO - requests == 1.2.3 18:37:26 INFO - wsgiref == 0.1.2 18:37:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:26 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 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:26 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 18:37:26 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 18:37:26 INFO - Using env: {'DISPLAY': ':0', 18:37:26 INFO - 'HOME': '/home/cltbld', 18:37:26 INFO - 'LANG': 'en_US.UTF-8', 18:37:26 INFO - 'LANGUAGE': 'en_US:en', 18:37:26 INFO - 'LOGNAME': 'cltbld', 18:37:26 INFO - 'MAIL': '/var/mail/cltbld', 18:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:26 INFO - 'MOZ_NO_REMOTE': '1', 18:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:26 INFO - 'NO_EM_RESTART': '1', 18:37:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:26 INFO - 'PWD': '/builds/slave/test', 18:37:26 INFO - 'SHELL': '/bin/bash', 18:37:26 INFO - 'SHLVL': '1', 18:37:26 INFO - 'TERM': 'linux', 18:37:26 INFO - 'TMOUT': '86400', 18:37:26 INFO - 'USER': 'cltbld', 18:37:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:26 INFO - '_': '/tools/buildbot/bin/python'} 18:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 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)) 18:37:26 INFO - Cleaning up... 18:37:26 INFO - Return code: 0 18:37:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:26 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 0x7f49ff951e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbd9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd75130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', '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 18:37:26 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 18:37:26 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 18:37:26 INFO - Using env: {'DISPLAY': ':0', 18:37:26 INFO - 'HOME': '/home/cltbld', 18:37:26 INFO - 'LANG': 'en_US.UTF-8', 18:37:26 INFO - 'LANGUAGE': 'en_US:en', 18:37:26 INFO - 'LOGNAME': 'cltbld', 18:37:26 INFO - 'MAIL': '/var/mail/cltbld', 18:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:26 INFO - 'MOZ_NO_REMOTE': '1', 18:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:26 INFO - 'NO_EM_RESTART': '1', 18:37:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:26 INFO - 'PWD': '/builds/slave/test', 18:37:26 INFO - 'SHELL': '/bin/bash', 18:37:26 INFO - 'SHLVL': '1', 18:37:26 INFO - 'TERM': 'linux', 18:37:26 INFO - 'TMOUT': '86400', 18:37:26 INFO - 'USER': 'cltbld', 18:37:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:26 INFO - '_': '/tools/buildbot/bin/python'} 18:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:37:26 INFO - Cleaning up... 18:37:26 INFO - Return code: 0 18:37:26 INFO - Running post-action listener: _resource_record_post_action 18:37:26 INFO - Running post-action listener: _start_resource_monitoring 18:37:26 INFO - Starting resource monitoring. 18:37:26 INFO - [mozharness: 2016-11-28 02:37:26.832831Z] Finished create-virtualenv step (success) 18:37:26 INFO - [mozharness: 2016-11-28 02:37:26.833348Z] Running install step. 18:37:26 INFO - Running pre-action listener: _resource_record_pre_action 18:37:26 INFO - Running main action method: install 18:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:37:27 INFO - Reading from file tmpfile_stdout 18:37:27 INFO - Detecting whether we're running mozinstall >=1.0... 18:37:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:37:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:37:27 INFO - Reading from file tmpfile_stdout 18:37:27 INFO - Output received: 18:37:27 INFO - Usage: mozinstall [options] installer 18:37:27 INFO - Options: 18:37:27 INFO - -h, --help show this help message and exit 18:37:27 INFO - -d DEST, --destination=DEST 18:37:27 INFO - Directory to install application into. [default: 18:37:27 INFO - "/builds/slave/test"] 18:37:27 INFO - --app=APP Application being installed. [default: firefox] 18:37:27 INFO - mkdir: /builds/slave/test/build/application 18:37:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:37:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:37:43 INFO - Reading from file tmpfile_stdout 18:37:43 INFO - Output received: 18:37:43 INFO - /builds/slave/test/build/application/firefox/firefox 18:37:43 INFO - Running post-action listener: _resource_record_post_action 18:37:43 INFO - [mozharness: 2016-11-28 02:37:43.142147Z] Finished install step (success) 18:37:43 INFO - [mozharness: 2016-11-28 02:37:43.142388Z] Running run-tests step. 18:37:43 INFO - Running pre-action listener: _resource_record_pre_action 18:37:43 INFO - Running main action method: run_tests 18:37:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:37:43 INFO - Python 2.7.3 18:37:43 INFO - Return code: 0 18:37:43 INFO - grabbing minidump binary from tooltool 18:37: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:37:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe03fc0>, '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 0xe04ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe05350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:37:43 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 18:37:43 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 18:37:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:37:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:37:43 INFO - Return code: 0 18:37:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:37:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:37:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:37:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:37:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:37:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 18:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 18:37:43 INFO - Using env: {'DISPLAY': ':0', 18:37:43 INFO - 'HOME': '/home/cltbld', 18:37:43 INFO - 'LANG': 'en_US.UTF-8', 18:37:43 INFO - 'LANGUAGE': 'en_US:en', 18:37:43 INFO - 'LOGNAME': 'cltbld', 18:37:43 INFO - 'MAIL': '/var/mail/cltbld', 18:37:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:37:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:43 INFO - 'MOZ_NO_REMOTE': '1', 18:37:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:37:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:43 INFO - 'NO_EM_RESTART': '1', 18:37:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:43 INFO - 'PWD': '/builds/slave/test', 18:37:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:37:43 INFO - 'SHELL': '/bin/bash', 18:37:43 INFO - 'SHLVL': '1', 18:37:43 INFO - 'TERM': 'linux', 18:37:43 INFO - 'TMOUT': '86400', 18:37:43 INFO - 'USER': 'cltbld', 18:37:43 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587', 18:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:43 INFO - '_': '/tools/buildbot/bin/python'} 18:37:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 18:37:43 INFO - mozversion application_buildid: 20161127172258 18:37:43 INFO - mozversion application_changeset: d263165be1c99d4181bdc3275e697d4bb85bafcc 18:37:43 INFO - mozversion application_display_name: Nightly 18:37:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:37:43 INFO - mozversion application_name: Firefox 18:37:43 INFO - mozversion application_remotingname: firefox 18:37:43 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 18:37:43 INFO - mozversion application_vendor: Mozilla 18:37:43 INFO - mozversion application_version: 53.0a1 18:37:43 INFO - mozversion platform_buildid: 20161127172258 18:37:43 INFO - mozversion platform_changeset: d263165be1c99d4181bdc3275e697d4bb85bafcc 18:37:43 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 18:37:43 INFO - mozversion platform_version: 53.0a1 18:37:43 INFO - using testdate: 1480300663 18:37:43 INFO - actual date: 1480300663 18:37:43 INFO - starting webserver on 'localhost:52005' 18:37:43 INFO - SUITE-START | Running 2 tests 18:37:43 INFO - TEST-START | dromaeo_css 18:37:43 INFO - operating with platform_type : linux_ 18:37:43 INFO - Initialising browser for dromaeo_css test... 18:37:43 INFO - TEST-INFO | started process 16319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY_c8n0/profile http://localhost:52005/getInfo.html) 18:37:47 INFO - PROCESS | 16319 | __metrics Screen width/height:1600/1200 18:37:47 INFO - PROCESS | 16319 | colorDepth:24 18:37:47 INFO - PROCESS | 16319 | Browser inner width/height: 1024/697 18:37:47 INFO - PROCESS | 16319 | __metrics 18:37:48 INFO - PROCESS | 16319 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:37:48 INFO - PROCESS | 16319 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:37:48 INFO - PROCESS | 16319 | console.error: PushService: 18:37:48 INFO - PROCESS | 16319 | stateChangeProcessEnqueue: Error transitioning state 18:37:48 INFO - PROCESS | 16319 | UnknownError 18:37:49 INFO - TEST-INFO | 16319: exit 0 18:37:49 INFO - Browser initialized. 18:37:49 INFO - Running cycle 1/1 for dromaeo_css test... 18:37:49 INFO - TEST-INFO | started process 16401 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY_c8n0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:01 INFO - PROCESS | 16401 | RSS: Main: 174018560 18:38:01 INFO - PROCESS | 16401 | 18:38:53 INFO - PROCESS | 16401 | 1480300733569 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] 18:41:06 INFO - PROCESS | 16401 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52005/tests/dromaeo/cssquery-ext.html) 18:41:08 INFO - PROCESS | 16401 | RSS: Main: 313073664 18:41:08 INFO - PROCESS | 16401 | 18:44:10 INFO - PROCESS | 16401 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-ext.html (next: http://localhost:52005/tests/dromaeo/cssquery-jquery.html) 18:44:11 INFO - PROCESS | 16401 | RSS: Main: 281137152 18:44:11 INFO - PROCESS | 16401 | 18:47:13 INFO - PROCESS | 16401 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52005/tests/dromaeo/cssquery-mootools.html) 18:47:14 INFO - PROCESS | 16401 | RSS: Main: 266932224 18:47:14 INFO - PROCESS | 16401 | 18:50:16 INFO - PROCESS | 16401 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52005/tests/dromaeo/cssquery-prototype.html) 18:50:17 INFO - PROCESS | 16401 | RSS: Main: 259526656 18:50:17 INFO - PROCESS | 16401 | 18:53:20 INFO - PROCESS | 16401 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52005/tests/dromaeo/cssquery-yui.html) 18:53:21 INFO - PROCESS | 16401 | RSS: Main: 224030720 18:53:21 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-yui.html (next: http://localhost:52005/tests/dromaeo/cssquery-dojo.html) 18:56:24 INFO - PROCESS | 16401 | RSS: Main: 316600320 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | __start_tp_report 18:56:24 INFO - PROCESS | 16401 | _x_x_mozilla_page_load 18:56:24 INFO - PROCESS | 16401 | _x_x_mozilla_page_load_details 18:56:24 INFO - PROCESS | 16401 | |i|pagename|runs| 18:56:24 INFO - PROCESS | 16401 | |0;dojo.html;2502.2368177705803;2542.0721436675617;2555.2972932443586;2556.412025234196;2571.5628343181656;4423.623991960687;4538.863834084978;4548.54514548545;4599.770011499424;4644.3962284902955;4568.520305367938;4595.058013107313;4629.120467111255;4668.533146685339;4707.505711900244;4201.1807697499;4226.894327641803;4283.635890949273;4290.528041915387;4383.736975781448;3938.995863712509;4248.447701798762;4285.207236661212;4327.285997810358;4360.6947513673895;4133.04939863833;4190.91618167638;4281.678874084425;4329.848455304081;4361.847335343264;4216.409702641633;4221.493420789525;4226.767527785971;4227.7463352198965;4228.302330115527;4198.223328684203;4199.790010499462;4203.55862634422;4208.55810139934;4213.789310534492;4575.290829921368;4616.469106052779;4636.513166117543;4637.304404339342;4676.3453116563705;4927.975360123177;4932.556069953722;4937.284093806414;4943.085529177114;4980.402351717781;4731.503192164808;4951.158303088448;4963.45402005779;4963.875903102416;4990.101781679296;4474.82100715971;4537.591616754475;4538.069695712371;4572.542745725401;4576.885577860514;4951.925721114186;4986.301917731521;4988.850334489971;4997.200447928314;5034.748262586856;4764.475907650156;4795;4796.928046079312;4837.879053023632;4859.173940430135;4774.236122220497;4831.9275210871865;4845.127876982177;4846.5638092571835;4867.5132486751045;4816.20532612115;4842.249451335048;4854.538818812207;4892.412910450768;4894.608431325485;4823.2282834746275;4825.445073816556;4827.517248275215;4856.490068542823;4871.805127794849;4073.1853629274265;4114.321137012359;4169.583041695806;4182.142660754538;4215.831366745358;3625.2930678517964;3654.488371627974;3658.6158453362546;3669.559652841676;3725.9813700931863;4255.914881702349;4263.786810659454;4264.6801489888385;4269.546051165185;4306.956930430718;4918.040982008545;4923.286123512071;4925.1381008324115;4929.0388374267395;4956.5786908112495;4122.814473348706;4160.812763425653;4171.040660648007;4198.034452075978;4236.25865473547;4453.1316393303;4458.8439404620685;4474.619657329164;4489.34904438861;4501.729896206238;4603.056373443436;4612.400387949545;4612.53874612536;4621.168189725747;4655.2784318430695;4764.928526072181;4778.211595086772;4823;4936.531029552187;4943.134951383421;4569.246074397687;4590.77046147698;4611.193041217841;4620.584147426748;4661.463931647768;4870.732109733999;4871.196252618278;4873.049755297683;4876.024795041077;4923.335349727741;4940.901181976412;4946.579540738933;4947.084789313988;4949.084419382426;4994;4670.322803193586;4729.9054018920115;4730.077634861306;4805.951940480551;4829.73436460998;4744.691595046311;4776.402949631296;4780.163471392562;4820.783064762164;4824.445188803192;4718.598919091778;4718.622510199244;4719.3628860103445;4748.786304616231;4755.500672429345;4635.397398338195;4641.094986477605;4688.765561721969;4829.44461386945;4837.2260438329695;4689.366935463669;4730.645201609962;4730.9763451182525;4738.763061846825;4799.880002999953 18:56:24 INFO - PROCESS | 16401 | |1;ext.html;559.8292520781162;565.6295126692019;569.5728203847116;575.7783253447421;576.9019266724656;27776.722232777665;38289.617103828954;39680.40479392808;39759.204815903664;43390;39989.200215995734;40459.19081618366;40462.59537404625;40480.79759601205;40495.19009619806;7469.626518674058;7662.923370766277;7664.386849052063;7665.540067595962;7665.8850117248285;7300.926990730078;7527;9653.855192172123;9870.309078364517;9877.506124693773;9999.25005624581;10063.496825158749;10063.849042264372;10070.496475176249;10073.294869359148;9883.308168428213;9904.365779646385;9906.653267135687;9932.602695892156;9962.402255864636;9819.116279534876;9878.90121098787;9899;9901.05939935705;9910.40537567741;35656.28687426263;35970.64029359699;36006.09984750376;36066.81966590176;36122;2978.300099476621;2980;3046.786724929256;3231.8384080795868;3355.551361392278;27148.728512714817;27689.86155069232;27730.634796471033;28604;28723.425531489254;3411.6076651185185;3426.0920855972936;3434.244466217428;3452.3440546296124;3460.584729832436;250052;253333;253477;254370.99999999814;255943;51139;51379;51398.74300628473;51405;51551.74224128856;54497.1825422619;54516.45483545193;54539.72730136324;54565.72717136389;54595.45404545983;14048.578542643738;14499.565013049625;14572.85427145715;14592.197429141499;14620.707585848224;9262.39794413361;9385.67150149755;9499.905000949902;9508.286878484141;9625.470599116976;15226.695466090616;15639.921800391152;15796.21018949071;16255.18724063792;16295.34818607266;6010.639361638316;6010.759569617167;6054.212952316171;6069.696515174223;6154.169187159676;747.2564798025999;808.3735105293327;848.8302339532119;857.2284943550729;1017.5624481473037;26371.604425933627;27470.862645687044;27790.43280182218;28027.159185224475;28217.435651286858;905.3300557587338;915.8214148241162;917.7659696777279;918.7427520294328;944.5135755086136;14188.290585470686;14584.927075364556;14605.415783368546;14693.412263509554;14914.105153690813;344444;346125;346263;346646;347161;47174.292385614244;47263;47348.28977565339;47433;47490.28764568534;1028.0901402259055;1065.4885654885543;1125.3697929159532;1136.272785417317;1138.3511398502774;24931.62602560927;28029.439411212068;28379.716202838532;28633.570496442153;28763.568546472237;40426.1914761709;40928.59071409367;40931;40992.59007410007;41016.79491602523;278515;279236;279571;279607;280223;24772.25683229506;25366.746332536437;25603.103891363713;25821.483570328113;35227.29545409129;39506;39580.40629390504;39753.80123099482;40160;40282;35138.64861351456;35182;35187.47218791669;35328;35400.82299588485;34543.65456345505;34979.30041399107;34979.30041399209;35007.47488787619;35071.824640876635;33640;33652.326953461285;34134.487982679784;34574.65425345714;34591.827040864635;30361.544576830922;32128;32391.190220244684;32591.837040815593;32834;24381.51236975286;24777.920526292426;24984.500309994062;25089.74910250874;25558.23325300244 18:56:24 INFO - PROCESS | 16401 | |2;jquery.html;341.81541967337637;359.22766052986094;369.46243216120547;375.33565588907635;378.6251610905203;12208.26750394977;13014.154079984806;13142.802857957126;13415.798763018538;16293.103879286635;15283.923580382083;15444.845551544482;15534.611634709137;15563.68872622547;15576.610584735387;3997;4757.6193904487645;4994;4999.975000124994;6010.699465026753;6075.878482430348;6077.1491991121275;6100.72546735398;6106.938930610681;6118.051701986199;5002.7248501332415;5247.868803279929;5292.126799078147;6067;6079.422454866803;5370.409254981949;5397.54120899724;5405.945840561112;5408.0535906216255;5408.567314614821;5061.822836200715;5167.9224811627855;5211.348581427322;5216.087184742655;5230.267762513251;11838.940805295919;11924.821127683092;12078.21491603043;12078.396080195955;12085.818712719229;1720.8021077576113;1784.295203394652;1786.2587026384035;1816.0919540229886;1849.9445016649522;8599.269062129664;8716.171963663443;8827.205551500332;9179.862302065474;9298.674546390845;1696.8897021693551;1781.8930864148192;1797.4427927342565;1880.2291060665198;1881.4637828218893;58972.57705672049;69238;69779;71072.64463677649;72204.27795722081;9368.470512961598;9436.716898493056;9711.465869377253;9810.705678829647;10015.799684006279;13394.799078013837;13402.53091141825;13405.932970335281;13411.329433528284;13412.0611557192;6655.667216639149;6977;7002.929970700329;7060.3292687194635;7127.251638577978;6414.454771344394;6583.604983700993;6796.286389928986;6802.4217941474535;6806.18325800907;4166.479190101238;4200.453940987713;4226.050985767412;4232;4289.442372491556;1784.8304411080926;1811.3117015534012;1816.8001519832808;1839.8804077734903;1860.2930886263134;802.9397795165386;826.2563692676518;834.745402652197;858.3433673239977;889.5685592487638;15455.149966751711;15595.532134035997;16054.75917861233;16252.268647910872;16965.066921319198;783.925527074927;799.17285609394;800.1318569352318;816.4611356504679;823.9258466738022;8710.520921349387;8743.256823170103;8912.242459390895;9041.457512549268;9472.100150485692;334175;341454;343229;343389;344510;75129.62435187899;76444;77707.22292777113;79214;79866.6006669963;842.3303473738392;864.2481041493814;874.3092956564243;875.7635438431715;878.7188099808128;14196;14651.267436628339;14919;14948.701025979637;15200.695986080438;36097.63902361048;36392.27215455729;36456.27087458289;36685.44971825478;37535.249295014495;49177.71830509463;49801.501984981136;50521.494785053146;51059.74470127626;51361.229581557054;6523.575967562093;6776.728930842711;7154.785356439315;7253.528520646352;7478.70085196586;6966.895496567454;9616.894057183514;9761.560729767036;11683.357415342238;11867.76264474723;9598.848138223459;9989.350692205275;10592.099671527698;10675.786484270426;10770.407627580558;10082.193424526167;10085.243606729378;10141.594336226468;10163.136133428741;10283.382997020202;10107.241956853119;10214.182865370902;10239.897601024193;10301.896981030093;10405.785656809005;6908.653463771598;6956.756513522002;6966.5471744336455;7098.574085554883;7145.21402645719;8991.78610887548;9747.756306092404;9839;9964.850527241952;10184.796304074025 18:56:24 INFO - PROCESS | 16401 | |3;mootools.html;1202.2846406388207;1331.1081575344522;1364.5428781358244;1382.198324971516;1634.5586691593262;5877.059670452729;6740.258571557131;7020.227774944752;7035.824104397386;7205.89191162133;7029.402500787426;7079.185893622235;7089.574625522472;7090.1846287676935;7093;1773.8314860088335;1833.294181740033;2054.9691754623655;2089.2165437960766;2091.362134548962;2028.3509277031355;2075.0247383729625;2082.500199952016;2087.112976984783;2095.486605781578;2055.085486958304;2066.0392917293457;2070.6686930091187;2071.9171233150732;2075.7612874519396;2040.816326530612;2049.9590008199752;2061.907214175366;2063.8039386258356;2064.3910046536307;1999.5800881814691;2012.2353505667752;2015.032784263547;2019.1519561784087;2028.3407892435018;6859.451243900526;6916.6541672916155;6968.512204145712;7063.788086357417;7072.434205263567;4230.957690423087;4359.956400436018;4363.629091527224;4366.01764602965;4368.5194628590825;2638.261286839688;2836.4752520783513;2859.4709978654014;2873.3534954635247;2910.5634154876725;840.1766269056359;861.7888617288742;879.3185281406896;905.7826121730803;910.6767097680295;9229.53852307382;9235.907640923571;9261.44431334122;9294.117058879396;9303.674371397035;3614.8192590370904;3797.430385442206;3847.9230415392094;3911.198204368098;3914.549826769901;7913.0504339478475;7919.049714034353;7922.049354077548;7924.445288829726;7927.3658107351275;4183.91632167355;4184.497860256789;4185.121124563876;4186.623203911602;4191.245575796386;3413.675700808419;3516.4197907345515;3718.9628103719156;3800.8859734208017;3808.8857334280015;3293.341331733615;3313.801171929692;3405.0465869556565;3421.0421082097055;3432.7940323580665;1067.80779459698;1089.841972913923;1103.3159441146381;1106.789709955106;1107.213878146509;665.7736369634348;811.5130921447085;823.2014945502852;823.5882058970515;850.1838235294064;4710.317004034396;4713.175194341045;4757.619390448755;4833.154198015333;4979.800807967641;402.52904102200495;406.3660689324663;412.2332461621382;412.6410023279766;415.08887990859773;3259.885903993349;3318.386098571772;3361.7478689097925;3365.8317084146183;3402.795832250073;7883.960580197062;8290.875636865452;8324;8328.458650187718;8381.874271885927;8276.420650554403;8280.213379728915;8284.378671599654;8285.461445006054;8289.41974061822;6250.593711408744;6381.4575761059905;6391.776287829996;6430.8456667016135;6433.710483028181;4541.591256786905;4617.699849509771;4659.44086709597;4691.413573303337;4762.142814293322;7588.089429268523;7600.125985511737;7649;7682.539047657158;7685.193054729173;7535.321821036133;7688.46180767341;7697.692092316244;7705.1524332324525;7810.297073263434;5459.07195776711;5616.9047035827625;6051.576389652682;6130.478909292582;6136.048912418582;6402.231732192166;6581.440577550962;6621.1392518972225;6767;6768;6005.909911351246;6013.776579814245;6107;6169.938300616936;6221.9377806221355;6078.118672792509;6086.6652334122045;6123.785667501616;6136.662483563268;6148.569600127948;5799.3040835100055;5900.11498275245;5908.527317814479;5911.083782013795;5915.940840591539;4304.956950430581;4375.234333991602;4421.314696222091;4469.44131983502;4554.27131658933;4453.153900758845;4487.775611219361;4492.438445194351;4494.865154045384;4631.745254011062 18:56:24 INFO - PROCESS | 16401 | |4;prototype.html;282.88543140028287;283.6879432624115;290.1440749788124;290.47134215727374;294.41411590934047;4572.679912406133;4758.405199350082;4775.919531901925;4801.975990120049;4821.517848215177;4587.1972404829185;4689.788959496822;4724.456687480941;4726.03116361146;4739.478657347698;3769.9057523561855;3778.414345776409;3781.2815565042692;3782.2813665403623;3797.658210761031;3592.1378869071364;3722.008725331948;3769.5853456119803;3771.8868433947027;3800.90497737556;3659.048647351681;3736.9252614947686;3744.7191460640424;3755.8685446009395;3778.9622103778884;3726.459663348799;3736.7010639148803;3743.681787048104;3751.756135851161;3765.4540091686827;3315.8176300303476;3320;3322.2026713588807;3324.335132973415;3341.365140623274;4426.535213802536;4608.400907881987;4652.74409907455;4666.580007799281;4674.602658774008;4188;4201.873943781691;4206.831726730927;4208.200441916057;4216.575469105277;4978.8755281118265;4988.6757360771435;4993.850184494471;4994.950050499521;5005.672362351487;3306.206510437502;3503.1767534629344;3510.3681337359267;3515.9648403515894;3518.384282750534;5142.945696132284;5223.947760522422;5232.241325007891;5235.842924712265;5248.527632513092;3574.5174401455993;3579.19468119671;3583.8924832255075;3588.5693716753635;3591.6049234584175;3604.711623070148;3620.945685814715;3624.7825130492256;3628.6552777486095;3630.546181727284;3923.7253392262837;4075.5109386873764;4112.835486580503;4170.643586373689;4175.290200665894;4936.975315123402;4940.876478088005;4950.381202349707;4955.663794180093;4956;1154.9133814963911;1179.3808250668467;1179.5314833032758;1200.8799120087922;1212.8120141377924;772.2277722277722;789.5736302396756;814.4095530740166;817.6811043692965;826.6321486938255;4281.828726850891;4295.312749960054;4298.871033868989;4347.934780978288;4363.5564084113;5010.298558201855;5014.674046186986;5041.319421878073;5043.5460808527405;5046.293518907282;4233.089885674594;4288.18524480354;4291.527931927485;4293.86212653647;4316.762578058237;2626.802989775736;2638.670166229221;2639.4721055788536;2640.009996251406;2644.206737978637;4633.745144017112;4641.837535686234;4645.117427688729;4669.276262179367;4669.836555720533;4961.578265847444;5085.779412940868;5217.3999990000875;5310.946890531122;5311.946880531223;4845.092944233432;4845.2732090185345;4859.2954021666665;4860.3681521401995;4865.367502224759;5175.896482070262;5194.766235519335;5202.505761952609;5209.218617207449;5229.189475631283;4899.461059283547;5037.92443113361;5059.217430413184;5068.873278168076;5101.464346243591;4857.1742803724155;4865.70805751656;4867.367242258625;4869.3479890082435;4875.24433712775;3526.7178625710394;3526.8486223404216;3545.5036294919246;3548.2371290172737;3562.7684200526883;4970.204767237225;5143.897122057613;5165.173572228426;5177.585793136464;5180.7409629517615;5022.422421421583;5032.446430892525;5032.471590483093;5038.672486288379;5039.143345631325;5009.323741294878;5010.344517405781;5011.774470148925;5021.443352560718;5033;4740.241561350167;4777.976110119566;4853.466118726867;4941.6293777966075;4954.331165292783;4907.950920490749;5061.620378471555;5072.365954255718;5101.132807422673;5102.377312108444;4937.506249375034;4953.628477864247;4955.1576232039915;4959.479254678206;4975.5717411503065 18:56:24 INFO - PROCESS | 16401 | |5;yui.html;630.7004173017817;631.5358211714392;650.7917466410747;706.6290197646235;709.3261401668415;11703.473343699532;12076.094292928043;12084.154109212359;12096.33470159141;12123.393830308494;12087.879121208785;12149.149559530837;12151.878481215184;12167.634970950863;12251.754964900696;603.4870360193844;618.653554009756;619.9783007594735;747.3423387419091;761.1095018827984;623.3299203356387;626.2297374229701;739.4786675393834;740.126650552348;750.3172113376828;804.8873157757919;815.8776183572452;815.8898548695939;816.2694388522269;816.6488409983705;804.7746630943316;805.5851236613149;805.9516429014278;806.1696452653777;806.6531391501652;468.72345316263227;470.57648116694907;569.1945896556368;595.1816252652603;595.4462350014485;11024.062954648783;11951.402429878472;11976.58081967127;12000.399980000964;12001.699957501045;11575.652730418102;11851;12144.696382590417;12395.75208495834;12510.687232819162;9490.478023708692;9632.855507167398;10920.836187457195;10932.289401188897;11100.222984391095;3954.209158168349;3996.0609256824623;4030.556638769733;4056.9594304057173;4127.545969943304;47423.76288118572;47903.52096479025;47976;48039.519604804205;49138;9504.95247523758;9542.33203675736;9550.856737148948;9563.760905977406;9598.520073996411;16039;16085;16170.27233774483;16209.189540523163;16230.51308460748;6085.726142323606;6136.17161683176;6168.845778855564;6185.752569897244;6208.161898143783;3196.2329041029984;3223.1942014496376;3278.8360581970805;3291.588551431071;3319.302946381287;2436.8903399347073;2514;2518.5592521309063;2529.6964364276405;2535.404180017694;441.5496193882286;450.293039927311;454.59313914046743;459.14827994071186;462.56518872259966;541.1720068295514;541.8780774325745;568.6815383385317;568.9914651280151;576.9278840144982;6889.862202756017;7222.386097181693;7577.886331705029;7693.192214817475;7786.727464538714;428.76846502888714;536.8752463588776;552.6104096611879;563.9548836093103;576.3602401334515;3325.467925131967;3346.682065203802;3449.0170301463963;3547.7693949893173;3722.3858063419775;39061.21877562433;39392.409113863316;45116;58604;68080.3191968074;35494.64505354965;37376.25247495035;46808.76595617;47725.76137119292;54538.72730636401;50135.24797127973;50201.74899125481;50204.74897625489;50258.748706256236;50879.49120508821;7692.96128029414;8523.190296921783;8528.530930798866;8662.610182541926;8689.13108689126;34663.653363466045;35941.64058359383;36305.81847090748;37185.25629487449;37823;38433.42349864698;39209;40901.590984090966;41977.790111049246;45072.323915140645;9887;10031.648892288735;10180.083792458418;10428.061474467335;10525.842112368473;11874.168808183344;12024;12073.698157546132;12450;13005.609831705064;11249.381284029458;11388.202825802115;11714.472848721658;11883;12402.441890114786;10427.270091093551;10483.580656773644;11180.776384472427;11846.229995050295;11964.222325548812;10705.304155229886;11074.667759967213;11205.775884482428;11243.100551955988;11446.94276528612;8586.270167035871;8671.523066231166;8698.391112622056;8846.424982376126;9033.59348829291;9522;9807.411555306418;9880.703578892644;9887.832115123063;9983.750406239902 18:56:24 INFO - PROCESS | 16401 | __end_tp_report 18:56:24 INFO - PROCESS | 16401 | __start_cc_report 18:56:24 INFO - PROCESS | 16401 | _x_x_mozilla_cycle_collect,1395 18:56:24 INFO - PROCESS | 16401 | __end_cc_report 18:56:24 INFO - PROCESS | 16401 | __startTimestamp1480301784703__endTimestamp 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | ------- Summary: start ------- 18:56:24 INFO - PROCESS | 16401 | Number of tests: 6 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | [#0] dojo.html Cycles:165 Average:4534.21 Median:4655.28 stddev:471.37 (10.1%) stddev-sans-first:445.05 18:56:24 INFO - PROCESS | 16401 | Values: 2502.2 2542.1 2555.3 2556.4 2571.6 4423.6 4538.9 4548.5 4599.8 4644.4 4568.5 4595.1 4629.1 4668.5 4707.5 4201.2 4226.9 4283.6 4290.5 4383.7 3939.0 4248.4 4285.2 4327.3 4360.7 4133.0 4190.9 4281.7 4329.8 4361.8 4216.4 4221.5 4226.8 4227.7 4228.3 4198.2 4199.8 4203.6 4208.6 4213.8 4575.3 4616.5 4636.5 4637.3 4676.3 4928.0 4932.6 4937.3 4943.1 4980.4 4731.5 4951.2 4963.5 4963.9 4990.1 4474.8 4537.6 4538.1 4572.5 4576.9 4951.9 4986.3 4988.9 4997.2 5034.7 4764.5 4795.0 4796.9 4837.9 4859.2 4774.2 4831.9 4845.1 4846.6 4867.5 4816.2 4842.2 4854.5 4892.4 4894.6 4823.2 4825.4 4827.5 4856.5 4871.8 4073.2 4114.3 4169.6 4182.1 4215.8 3625.3 3654.5 3658.6 3669.6 3726.0 4255.9 4263.8 4264.7 4269.5 4307.0 4918.0 4923.3 4925.1 4929.0 4956.6 4122.8 4160.8 4171.0 4198.0 4236.3 4453.1 4458.8 4474.6 4489.3 4501.7 4603.1 4612.4 4612.5 4621.2 4655.3 4764.9 4778.2 4823.0 4936.5 4943.1 4569.2 4590.8 4611.2 4620.6 4661.5 4870.7 4871.2 4873.0 4876.0 4923.3 4940.9 4946.6 4947.1 4949.1 4994.0 4670.3 4729.9 4730.1 4806.0 4829.7 4744.7 4776.4 4780.2 4820.8 4824.4 4718.6 4718.6 4719.4 4748.8 4755.5 4635.4 4641.1 4688.8 4829.4 4837.2 4689.4 4730.6 4731.0 4738.8 4799.9 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | [#1] ext.html Cycles:180 Average:44883.33 Median:35160.32 stddev:77442.95 (220.3%) stddev-sans-first:77588.69 18:56:24 INFO - PROCESS | 16401 | Values: 559.8 565.6 569.6 575.8 576.9 27776.7 38289.6 39680.4 39759.2 43390.0 39989.2 40459.2 40462.6 40480.8 40495.2 7469.6 7662.9 7664.4 7665.5 7665.9 7300.9 7527.0 9653.9 9870.3 9877.5 9999.3 10063.5 10063.8 10070.5 10073.3 9883.3 9904.4 9906.7 9932.6 9962.4 9819.1 9878.9 9899.0 9901.1 9910.4 35656.3 35970.6 36006.1 36066.8 36122.0 2978.3 2980.0 3046.8 3231.8 3355.6 27148.7 27689.9 27730.6 28604.0 28723.4 3411.6 3426.1 3434.2 3452.3 3460.6 250052.0 253333.0 253477.0 254371.0 255943.0 51139.0 51379.0 51398.7 51405.0 51551.7 54497.2 54516.5 54539.7 54565.7 54595.5 14048.6 14499.6 14572.9 14592.2 14620.7 9262.4 9385.7 9499.9 9508.3 9625.5 15226.7 15639.9 15796.2 16255.2 16295.3 6010.6 6010.8 6054.2 6069.7 6154.2 747.3 808.4 848.8 857.2 1017.6 26371.6 27470.9 27790.4 28027.2 28217.4 905.3 915.8 917.8 918.7 944.5 14188.3 14584.9 14605.4 14693.4 14914.1 344444.0 346125.0 346263.0 346646.0 347161.0 47174.3 47263.0 47348.3 47433.0 47490.3 1028.1 1065.5 1125.4 1136.3 1138.4 24931.6 28029.4 28379.7 28633.6 28763.6 40426.2 40928.6 40931.0 40992.6 41016.8 278515.0 279236.0 279571.0 279607.0 280223.0 24772.3 25366.7 25603.1 25821.5 35227.3 39506.0 39580.4 39753.8 40160.0 40282.0 35138.6 35182.0 35187.5 35328.0 35400.8 34543.7 34979.3 34979.3 35007.5 35071.8 33640.0 33652.3 34134.5 34574.7 34591.8 30361.5 32128.0 32391.2 32591.8 32834.0 24381.5 24777.9 24984.5 25089.7 25558.2 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | [#2] jquery.html Cycles:180 Average:22362.53 Median:28060.78 stddev:56799.07 (202.4%) stddev-sans-first:56934.34 18:56:24 INFO - PROCESS | 16401 | Values: 341.8 359.2 369.5 375.3 378.6 12208.3 13014.2 13142.8 13415.8 16293.1 15283.9 15444.8 15534.6 15563.7 15576.6 3997.0 4757.6 4994.0 5000.0 6010.7 6075.9 6077.1 6100.7 6106.9 6118.1 5002.7 5247.9 5292.1 6067.0 6079.4 5370.4 5397.5 5405.9 5408.1 5408.6 5061.8 5167.9 5211.3 5216.1 5230.3 11838.9 11924.8 12078.2 12078.4 12085.8 1720.8 1784.3 1786.3 1816.1 1849.9 8599.3 8716.2 8827.2 9179.9 9298.7 1696.9 1781.9 1797.4 1880.2 1881.5 58972.6 69238.0 69779.0 71072.6 72204.3 9368.5 9436.7 9711.5 9810.7 10015.8 13394.8 13402.5 13405.9 13411.3 13412.1 6655.7 6977.0 7002.9 7060.3 7127.3 6414.5 6583.6 6796.3 6802.4 6806.2 4166.5 4200.5 4226.1 4232.0 4289.4 1784.8 1811.3 1816.8 1839.9 1860.3 802.9 826.3 834.7 858.3 889.6 15455.1 15595.5 16054.8 16252.3 16965.1 783.9 799.2 800.1 816.5 823.9 8710.5 8743.3 8912.2 9041.5 9472.1 334175.0 341454.0 343229.0 343389.0 344510.0 75129.6 76444.0 77707.2 79214.0 79866.6 842.3 864.2 874.3 875.8 878.7 14196.0 14651.3 14919.0 14948.7 15200.7 36097.6 36392.3 36456.3 36685.4 37535.2 49177.7 49801.5 50521.5 51059.7 51361.2 6523.6 6776.7 7154.8 7253.5 7478.7 6966.9 9616.9 9761.6 11683.4 11867.8 9598.8 9989.4 10592.1 10675.8 10770.4 10082.2 10085.2 10141.6 10163.1 10283.4 10107.2 10214.2 10239.9 10301.9 10405.8 6908.7 6956.8 6966.5 7098.6 7145.2 8991.8 9747.8 9839.0 9964.9 10184.8 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | [#3] mootools.html Cycles:180 Average:4597.86 Median:4735.40 stddev:2479.02 (52.4%) stddev-sans-first:2472.83 18:56:24 INFO - PROCESS | 16401 | Values: 1202.3 1331.1 1364.5 1382.2 1634.6 5877.1 6740.3 7020.2 7035.8 7205.9 7029.4 7079.2 7089.6 7090.2 7093.0 1773.8 1833.3 2055.0 2089.2 2091.4 2028.4 2075.0 2082.5 2087.1 2095.5 2055.1 2066.0 2070.7 2071.9 2075.8 2040.8 2050.0 2061.9 2063.8 2064.4 1999.6 2012.2 2015.0 2019.2 2028.3 6859.5 6916.7 6968.5 7063.8 7072.4 4231.0 4360.0 4363.6 4366.0 4368.5 2638.3 2836.5 2859.5 2873.4 2910.6 840.2 861.8 879.3 905.8 910.7 9229.5 9235.9 9261.4 9294.1 9303.7 3614.8 3797.4 3847.9 3911.2 3914.5 7913.1 7919.0 7922.0 7924.4 7927.4 4183.9 4184.5 4185.1 4186.6 4191.2 3413.7 3516.4 3719.0 3800.9 3808.9 3293.3 3313.8 3405.0 3421.0 3432.8 1067.8 1089.8 1103.3 1106.8 1107.2 665.8 811.5 823.2 823.6 850.2 4710.3 4713.2 4757.6 4833.2 4979.8 402.5 406.4 412.2 412.6 415.1 3259.9 3318.4 3361.7 3365.8 3402.8 7884.0 8290.9 8324.0 8328.5 8381.9 8276.4 8280.2 8284.4 8285.5 8289.4 6250.6 6381.5 6391.8 6430.8 6433.7 4541.6 4617.7 4659.4 4691.4 4762.1 7588.1 7600.1 7649.0 7682.5 7685.2 7535.3 7688.5 7697.7 7705.2 7810.3 5459.1 5616.9 6051.6 6130.5 6136.0 6402.2 6581.4 6621.1 6767.0 6768.0 6005.9 6013.8 6107.0 6169.9 6221.9 6078.1 6086.7 6123.8 6136.7 6148.6 5799.3 5900.1 5908.5 5911.1 5915.9 4305.0 4375.2 4421.3 4469.4 4554.3 4453.2 4487.8 4492.4 4494.9 4631.7 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | [#4] prototype.html Cycles:180 Average:4122.43 Median:4672.22 stddev:1214.71 (26.0%) stddev-sans-first:1183.44 18:56:24 INFO - PROCESS | 16401 | Values: 282.9 283.7 290.1 290.5 294.4 4572.7 4758.4 4775.9 4802.0 4821.5 4587.2 4689.8 4724.5 4726.0 4739.5 3769.9 3778.4 3781.3 3782.3 3797.7 3592.1 3722.0 3769.6 3771.9 3800.9 3659.0 3736.9 3744.7 3755.9 3779.0 3726.5 3736.7 3743.7 3751.8 3765.5 3315.8 3320.0 3322.2 3324.3 3341.4 4426.5 4608.4 4652.7 4666.6 4674.6 4188.0 4201.9 4206.8 4208.2 4216.6 4978.9 4988.7 4993.9 4995.0 5005.7 3306.2 3503.2 3510.4 3516.0 3518.4 5142.9 5223.9 5232.2 5235.8 5248.5 3574.5 3579.2 3583.9 3588.6 3591.6 3604.7 3620.9 3624.8 3628.7 3630.5 3923.7 4075.5 4112.8 4170.6 4175.3 4937.0 4940.9 4950.4 4955.7 4956.0 1154.9 1179.4 1179.5 1200.9 1212.8 772.2 789.6 814.4 817.7 826.6 4281.8 4295.3 4298.9 4347.9 4363.6 5010.3 5014.7 5041.3 5043.5 5046.3 4233.1 4288.2 4291.5 4293.9 4316.8 2626.8 2638.7 2639.5 2640.0 2644.2 4633.7 4641.8 4645.1 4669.3 4669.8 4961.6 5085.8 5217.4 5310.9 5311.9 4845.1 4845.3 4859.3 4860.4 4865.4 5175.9 5194.8 5202.5 5209.2 5229.2 4899.5 5037.9 5059.2 5068.9 5101.5 4857.2 4865.7 4867.4 4869.3 4875.2 3526.7 3526.8 3545.5 3548.2 3562.8 4970.2 5143.9 5165.2 5177.6 5180.7 5022.4 5032.4 5032.5 5038.7 5039.1 5009.3 5010.3 5011.8 5021.4 5033.0 4740.2 4778.0 4853.5 4941.6 4954.3 4908.0 5061.6 5072.4 5101.1 5102.4 4937.5 4953.6 4955.2 4959.5 4975.6 18:56:24 INFO - PROCESS | 16401 | 18:56:24 INFO - PROCESS | 16401 | [#5] yui.html Cycles:180 Average:13075.07 Median:456.87 stddev:15353.10 (3360.5%) stddev-sans-first:15367.73 18:56:24 INFO - PROCESS | 16401 | Values: 630.7 631.5 650.8 706.6 709.3 11703.5 12076.1 12084.2 12096.3 12123.4 12087.9 12149.1 12151.9 12167.6 12251.8 603.5 618.7 620.0 747.3 761.1 623.3 626.2 739.5 740.1 750.3 804.9 815.9 815.9 816.3 816.6 804.8 805.6 806.0 806.2 806.7 468.7 470.6 569.2 595.2 595.4 11024.1 11951.4 11976.6 12000.4 12001.7 11575.7 11851.0 12144.7 12395.8 12510.7 9490.5 9632.9 10920.8 10932.3 11100.2 3954.2 3996.1 4030.6 4057.0 4127.5 47423.8 47903.5 47976.0 48039.5 49138.0 9505.0 9542.3 9550.9 9563.8 9598.5 16039.0 16085.0 16170.3 16209.2 16230.5 6085.7 6136.2 6168.8 6185.8 6208.2 3196.2 3223.2 3278.8 3291.6 3319.3 2436.9 2514.0 2518.6 2529.7 2535.4 441.5 450.3 454.6 459.1 462.6 541.2 541.9 568.7 569.0 576.9 6889.9 7222.4 7577.9 7693.2 7786.7 428.8 536.9 552.6 564.0 576.4 3325.5 3346.7 3449.0 3547.8 3722.4 39061.2 39392.4 45116.0 58604.0 68080.3 35494.6 37376.3 46808.8 47725.8 54538.7 50135.2 50201.7 50204.7 50258.7 50879.5 7693.0 8523.2 8528.5 8662.6 8689.1 34663.7 35941.6 36305.8 37185.3 37823.0 38433.4 39209.0 40901.6 41977.8 45072.3 9887.0 10031.6 10180.1 10428.1 10525.8 11874.2 12024.0 12073.7 12450.0 13005.6 11249.4 11388.2 11714.5 11883.0 12402.4 10427.3 10483.6 11180.8 11846.2 11964.2 10705.3 11074.7 11205.8 11243.1 11446.9 8586.3 8671.5 8698.4 8846.4 9033.6 9522.0 9807.4 9880.7 9887.8 9983.8 18:56:24 INFO - PROCESS | 16401 | -------- Summary: end -------- 18:56:24 INFO - PROCESS | 16401 | 18:56:25 INFO - TEST-INFO | 16401: exit 0 18:56:25 INFO - TEST-OK | dromaeo_css | took 1122295ms 18:56:25 INFO - TEST-START | kraken 18:56:25 INFO - operating with platform_type : linux_ 18:56:25 INFO - Initialising browser for kraken test... 18:56:25 INFO - TEST-INFO | started process 16704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5CUjD8/profile http://localhost:52005/getInfo.html) 18:56:29 INFO - PROCESS | 16704 | __metrics Screen width/height:1600/1200 18:56:29 INFO - PROCESS | 16704 | colorDepth:24 18:56:29 INFO - PROCESS | 16704 | Browser inner width/height: 1024/697 18:56:29 INFO - PROCESS | 16704 | __metrics 18:56:30 INFO - PROCESS | 16704 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:56:30 INFO - PROCESS | 16704 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:56:31 INFO - PROCESS | 16704 | console.error: PushService: 18:56:31 INFO - PROCESS | 16704 | stateChangeProcessEnqueue: Error transitioning state 18:56:31 INFO - PROCESS | 16704 | UnknownError 18:56:31 INFO - TEST-INFO | 16704: exit 0 18:56:31 INFO - Browser initialized. 18:56:31 INFO - Running cycle 1/1 for kraken test... 18:56:31 INFO - TEST-INFO | started process 16784 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5CUjD8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:56:44 INFO - PROCESS | 16784 | RSS: Main: 174161920 18:56:44 INFO - PROCESS | 16784 | 18:56:46 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-beat-detection) 18:56:47 INFO - PROCESS | 16784 | RSS: Main: 239276032 18:56:47 INFO - PROCESS | 16784 | 18:56:50 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-dft) 18:56:51 INFO - PROCESS | 16784 | RSS: Main: 217980928 18:56:51 INFO - PROCESS | 16784 | 18:56:54 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-fft) 18:56:54 INFO - PROCESS | 16784 | RSS: Main: 299520000 18:56:54 INFO - PROCESS | 16784 | 18:56:57 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-oscillator) 18:56:57 INFO - PROCESS | 16784 | RSS: Main: 211144704 18:56:57 INFO - PROCESS | 16784 | 18:56:59 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52005/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:57:00 INFO - PROCESS | 16784 | RSS: Main: 214081536 18:57:00 INFO - PROCESS | 16784 | 18:57:03 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52005/tests/kraken/driver.html?testName=imaging-darkroom) 18:57:04 INFO - PROCESS | 16784 | RSS: Main: 254017536 18:57:04 INFO - PROCESS | 16784 | 18:57:07 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52005/tests/kraken/driver.html?testName=imaging-desaturate) 18:57:08 INFO - PROCESS | 16784 | RSS: Main: 248205312 18:57:08 INFO - PROCESS | 16784 | 18:57:11 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52005/tests/kraken/driver.html?testName=json-parse-financial) 18:57:12 INFO - PROCESS | 16784 | RSS: Main: 249565184 18:57:12 INFO - PROCESS | 16784 | 18:57:13 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52005/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:57:14 INFO - PROCESS | 16784 | RSS: Main: 212647936 18:57:14 INFO - PROCESS | 16784 | 18:57:16 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:57:16 INFO - PROCESS | 16784 | RSS: Main: 220606464 18:57:16 INFO - PROCESS | 16784 | 18:57:19 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:57:19 INFO - PROCESS | 16784 | RSS: Main: 228716544 18:57:19 INFO - PROCESS | 16784 | 18:57:22 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:57:23 INFO - PROCESS | 16784 | RSS: Main: 237281280 18:57:23 INFO - PROCESS | 16784 | 18:57:26 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:57:26 INFO - PROCESS | 16784 | RSS: Main: 234627072 18:57:26 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52005/tests/kraken/driver.html?testName=ai-astar) 18:57:28 INFO - PROCESS | 16784 | RSS: Main: 285077504 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | __start_tp_report 18:57:28 INFO - PROCESS | 16784 | _x_x_mozilla_page_load 18:57:28 INFO - PROCESS | 16784 | _x_x_mozilla_page_load_details 18:57:28 INFO - PROCESS | 16784 | |i|pagename|runs| 18:57:28 INFO - PROCESS | 16784 | |0;ai-astar;112;114;114;114;114;114;114;114;113;117 18:57:28 INFO - PROCESS | 16784 | |1;audio-beat-detection;158;129;131;174;130;132;132;128;153;131 18:57:28 INFO - PROCESS | 16784 | |2;audio-dft;161;161;161;164;160;163;162;162;162;163 18:57:28 INFO - PROCESS | 16784 | |3;audio-fft;76;76;76;112;77;77;75;78;98;76 18:57:28 INFO - PROCESS | 16784 | |4;audio-oscillator;84;82;118;83;85;84;99;85;84;106 18:57:28 INFO - PROCESS | 16784 | |5;imaging-gaussian-blur;118;118;118;118;117;118;119;118;118;117 18:57:28 INFO - PROCESS | 16784 | |6;imaging-darkroom;146;147;147;146;147;147;146;147;147;148 18:57:28 INFO - PROCESS | 16784 | |7;imaging-desaturate;95;95;95;96;95;95;96;95;95;95 18:57:28 INFO - PROCESS | 16784 | |8;json-parse-financial;63;62;62;62;64;65;64;63;63;64 18:57:28 INFO - PROCESS | 16784 | |9;json-stringify-tinderbox;64;65;66;65;66;66;66;67;67;67 18:57:28 INFO - PROCESS | 16784 | |10;stanford-crypto-aes;85;94;85;88;86;85;84;84;90;84 18:57:28 INFO - PROCESS | 16784 | |11;stanford-crypto-ccm;148;146;143;145;140;150;138;148;189;141 18:57:28 INFO - PROCESS | 16784 | |12;stanford-crypto-pbkdf2;181;177;179;177;187;177;176;176;181;177 18:57:28 INFO - PROCESS | 16784 | |13;stanford-crypto-sha256-iterative;71;70;71;69;70;70;72;70;71;69 18:57:28 INFO - PROCESS | 16784 | __end_tp_report 18:57:28 INFO - PROCESS | 16784 | __start_cc_report 18:57:28 INFO - PROCESS | 16784 | _x_x_mozilla_cycle_collect,1960 18:57:28 INFO - PROCESS | 16784 | __end_cc_report 18:57:28 INFO - PROCESS | 16784 | __startTimestamp1480301848984__endTimestamp 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | ------- Summary: start ------- 18:57:28 INFO - PROCESS | 16784 | Number of tests: 14 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:1.25 (1.1%) stddev-sans-first:1.09 18:57:28 INFO - PROCESS | 16784 | Values: 112.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 113.0 117.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:132.00 stddev:16.00 (12.1%) stddev-sans-first:15.55 18:57:28 INFO - PROCESS | 16784 | Values: 158.0 129.0 131.0 174.0 130.0 132.0 132.0 128.0 153.0 131.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 18:57:28 INFO - PROCESS | 16784 | Values: 161.0 161.0 161.0 164.0 160.0 163.0 162.0 162.0 162.0 163.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.50 stddev:12.54 (16.4%) stddev-sans-first:13.10 18:57:28 INFO - PROCESS | 16784 | Values: 76.0 76.0 76.0 112.0 77.0 77.0 75.0 78.0 98.0 76.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:84.00 stddev:12.39 (14.8%) stddev-sans-first:12.88 18:57:28 INFO - PROCESS | 16784 | Values: 84.0 82.0 118.0 83.0 85.0 84.0 99.0 85.0 84.0 106.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:57:28 INFO - PROCESS | 16784 | Values: 118.0 118.0 118.0 118.0 117.0 118.0 119.0 118.0 118.0 117.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 18:57:28 INFO - PROCESS | 16784 | Values: 146.0 147.0 147.0 146.0 147.0 147.0 146.0 147.0 147.0 148.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:57:28 INFO - PROCESS | 16784 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.50 stddev:1.03 (1.6%) stddev-sans-first:1.09 18:57:28 INFO - PROCESS | 16784 | Values: 63.0 62.0 62.0 62.0 64.0 65.0 64.0 63.0 63.0 64.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:0.78 18:57:28 INFO - PROCESS | 16784 | Values: 64.0 65.0 66.0 65.0 66.0 66.0 66.0 67.0 67.0 67.0 18:57:28 INFO - PROCESS | 16784 | 18:57:28 INFO - PROCESS | 16784 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:85.50 stddev:3.27 (3.8%) stddev-sans-first:3.43 18:57:28 INFO - PROCESS | 16784 | Values: 85.0 94.0 85.0 88.0 86.0 85.0 84.0 84.0 90.0 84.0 18:57:28 INFO - PROCESS | 16784 | 18:57:29 INFO - PROCESS | 16784 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:147.00 stddev:14.64 (10.0%) stddev-sans-first:15.53 18:57:29 INFO - PROCESS | 16784 | Values: 148.0 146.0 143.0 145.0 140.0 150.0 138.0 148.0 189.0 141.0 18:57:29 INFO - PROCESS | 16784 | 18:57:29 INFO - PROCESS | 16784 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:178.00 stddev:3.43 (1.9%) stddev-sans-first:3.54 18:57:29 INFO - PROCESS | 16784 | Values: 181.0 177.0 179.0 177.0 187.0 177.0 176.0 176.0 181.0 177.0 18:57:29 INFO - PROCESS | 16784 | 18:57:29 INFO - PROCESS | 16784 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.50 stddev:0.95 (1.3%) stddev-sans-first:0.97 18:57:29 INFO - PROCESS | 16784 | Values: 71.0 70.0 71.0 69.0 70.0 70.0 72.0 70.0 71.0 69.0 18:57:29 INFO - PROCESS | 16784 | -------- Summary: end -------- 18:57:29 INFO - PROCESS | 16784 | 18:57:30 INFO - TEST-INFO | 16784: exit 0 18:57:30 INFO - TEST-OK | kraken | took 64786ms 18:57:30 INFO - SUITE-END | took 1187s 18:57:30 INFO - Completed test suite (00:19:47) 18:57:30 INFO - javascript benchmark 18:57:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2502.2368177705803, 2542.0721436675617, 2555.2972932443586, 2556.412025234196, 2571.5628343181656, 4423.623991960687, 4538.863834084978, 4548.54514548545, 4599.770011499424, 4644.3962284902955, 4568.520305367938, 4595.058013107313, 4629.120467111255, 4668.533146685339, 4707.505711900244, 4201.1807697499, 4226.894327641803, 4283.635890949273, 4290.528041915387, 4383.736975781448, 3938.995863712509, 4248.447701798762, 4285.207236661212, 4327.285997810358, 4360.6947513673895, 4133.04939863833, 4190.91618167638, 4281.678874084425, 4329.848455304081, 4361.847335343264, 4216.409702641633, 4221.493420789525, 4226.767527785971, 4227.7463352198965, 4228.302330115527, 4198.223328684203, 4199.790010499462, 4203.55862634422, 4208.55810139934, 4213.789310534492, 4575.290829921368, 4616.469106052779, 4636.513166117543, 4637.304404339342, 4676.3453116563705, 4927.975360123177, 4932.556069953722, 4937.284093806414, 4943.085529177114, 4980.402351717781, 4731.503192164808, 4951.158303088448, 4963.45402005779, 4963.875903102416, 4990.101781679296, 4474.82100715971, 4537.591616754475, 4538.069695712371, 4572.542745725401, 4576.885577860514, 4951.925721114186, 4986.301917731521, 4988.850334489971, 4997.200447928314, 5034.748262586856, 4764.475907650156, 4795.0, 4796.928046079312, 4837.879053023632, 4859.173940430135, 4774.236122220497, 4831.9275210871865, 4845.127876982177, 4846.5638092571835, 4867.5132486751045, 4816.20532612115, 4842.249451335048, 4854.538818812207, 4892.412910450768, 4894.608431325485, 4823.2282834746275, 4825.445073816556, 4827.517248275215, 4856.490068542823, 4871.805127794849, 4073.1853629274265, 4114.321137012359, 4169.583041695806, 4182.142660754538, 4215.831366745358, 3625.2930678517964, 3654.488371627974, 3658.6158453362546, 3669.559652841676, 3725.9813700931863, 4255.914881702349, 4263.786810659454, 4264.6801489888385, 4269.546051165185, 4306.956930430718, 4918.040982008545, 4923.286123512071, 4925.1381008324115, 4929.0388374267395, 4956.5786908112495, 4122.814473348706, 4160.812763425653, 4171.040660648007, 4198.034452075978, 4236.25865473547, 4453.1316393303, 4458.8439404620685, 4474.619657329164, 4489.34904438861, 4501.729896206238, 4603.056373443436, 4612.400387949545, 4612.53874612536, 4621.168189725747, 4655.2784318430695, 4764.928526072181, 4778.211595086772, 4823.0, 4936.531029552187, 4943.134951383421, 4569.246074397687, 4590.77046147698, 4611.193041217841, 4620.584147426748, 4661.463931647768, 4870.732109733999, 4871.196252618278, 4873.049755297683, 4876.024795041077, 4923.335349727741, 4940.901181976412, 4946.579540738933, 4947.084789313988, 4949.084419382426, 4994.0, 4670.322803193586, 4729.9054018920115, 4730.077634861306, 4805.951940480551, 4829.73436460998, 4744.691595046311, 4776.402949631296, 4780.163471392562, 4820.783064762164, 4824.445188803192, 4718.598919091778, 4718.622510199244, 4719.3628860103445, 4748.786304616231, 4755.500672429345, 4635.397398338195, 4641.094986477605, 4688.765561721969, 4829.44461386945, 4837.2260438329695, 4689.366935463669, 4730.645201609962, 4730.9763451182525, 4738.763061846825, 4799.880002999953], "value": 4504.181521694206, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.8292520781162, 565.6295126692019, 569.5728203847116, 575.7783253447421, 576.9019266724656, 27776.722232777665, 38289.617103828954, 39680.40479392808, 39759.204815903664, 43390.0, 39989.200215995734, 40459.19081618366, 40462.59537404625, 40480.79759601205, 40495.19009619806, 7469.626518674058, 7662.923370766277, 7664.386849052063, 7665.540067595962, 7665.8850117248285, 7300.926990730078, 7527.0, 9653.855192172123, 9870.309078364517, 9877.506124693773, 9999.25005624581, 10063.496825158749, 10063.849042264372, 10070.496475176249, 10073.294869359148, 9883.308168428213, 9904.365779646385, 9906.653267135687, 9932.602695892156, 9962.402255864636, 9819.116279534876, 9878.90121098787, 9899.0, 9901.05939935705, 9910.40537567741, 35656.28687426263, 35970.64029359699, 36006.09984750376, 36066.81966590176, 36122.0, 2978.300099476621, 2980.0, 3046.786724929256, 3231.8384080795868, 3355.551361392278, 27148.728512714817, 27689.86155069232, 27730.634796471033, 28604.0, 28723.425531489254, 3411.6076651185185, 3426.0920855972936, 3434.244466217428, 3452.3440546296124, 3460.584729832436, 250052.0, 253333.0, 253477.0, 254370.99999999814, 255943.0, 51139.0, 51379.0, 51398.74300628473, 51405.0, 51551.74224128856, 54497.1825422619, 54516.45483545193, 54539.72730136324, 54565.72717136389, 54595.45404545983, 14048.578542643738, 14499.565013049625, 14572.85427145715, 14592.197429141499, 14620.707585848224, 9262.39794413361, 9385.67150149755, 9499.905000949902, 9508.286878484141, 9625.470599116976, 15226.695466090616, 15639.921800391152, 15796.21018949071, 16255.18724063792, 16295.34818607266, 6010.639361638316, 6010.759569617167, 6054.212952316171, 6069.696515174223, 6154.169187159676, 747.2564798025999, 808.3735105293327, 848.8302339532119, 857.2284943550729, 1017.5624481473037, 26371.604425933627, 27470.862645687044, 27790.43280182218, 28027.159185224475, 28217.435651286858, 905.3300557587338, 915.8214148241162, 917.7659696777279, 918.7427520294328, 944.5135755086136, 14188.290585470686, 14584.927075364556, 14605.415783368546, 14693.412263509554, 14914.105153690813, 344444.0, 346125.0, 346263.0, 346646.0, 347161.0, 47174.292385614244, 47263.0, 47348.28977565339, 47433.0, 47490.28764568534, 1028.0901402259055, 1065.4885654885543, 1125.3697929159532, 1136.272785417317, 1138.3511398502774, 24931.62602560927, 28029.439411212068, 28379.716202838532, 28633.570496442153, 28763.568546472237, 40426.1914761709, 40928.59071409367, 40931.0, 40992.59007410007, 41016.79491602523, 278515.0, 279236.0, 279571.0, 279607.0, 280223.0, 24772.25683229506, 25366.746332536437, 25603.103891363713, 25821.483570328113, 35227.29545409129, 39506.0, 39580.40629390504, 39753.80123099482, 40160.0, 40282.0, 35138.64861351456, 35182.0, 35187.47218791669, 35328.0, 35400.82299588485, 34543.65456345505, 34979.30041399107, 34979.30041399209, 35007.47488787619, 35071.824640876635, 33640.0, 33652.326953461285, 34134.487982679784, 34574.65425345714, 34591.827040864635, 30361.544576830922, 32128.0, 32391.190220244684, 32591.837040815593, 32834.0, 24381.51236975286, 24777.920526292426, 24984.500309994062, 25089.74910250874, 25558.23325300244], "value": 17319.78540618517, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.81541967337637, 359.22766052986094, 369.46243216120547, 375.33565588907635, 378.6251610905203, 12208.26750394977, 13014.154079984806, 13142.802857957126, 13415.798763018538, 16293.103879286635, 15283.923580382083, 15444.845551544482, 15534.611634709137, 15563.68872622547, 15576.610584735387, 3997.0, 4757.6193904487645, 4994.0, 4999.975000124994, 6010.699465026753, 6075.878482430348, 6077.1491991121275, 6100.72546735398, 6106.938930610681, 6118.051701986199, 5002.7248501332415, 5247.868803279929, 5292.126799078147, 6067.0, 6079.422454866803, 5370.409254981949, 5397.54120899724, 5405.945840561112, 5408.0535906216255, 5408.567314614821, 5061.822836200715, 5167.9224811627855, 5211.348581427322, 5216.087184742655, 5230.267762513251, 11838.940805295919, 11924.821127683092, 12078.21491603043, 12078.396080195955, 12085.818712719229, 1720.8021077576113, 1784.295203394652, 1786.2587026384035, 1816.0919540229886, 1849.9445016649522, 8599.269062129664, 8716.171963663443, 8827.205551500332, 9179.862302065474, 9298.674546390845, 1696.8897021693551, 1781.8930864148192, 1797.4427927342565, 1880.2291060665198, 1881.4637828218893, 58972.57705672049, 69238.0, 69779.0, 71072.64463677649, 72204.27795722081, 9368.470512961598, 9436.716898493056, 9711.465869377253, 9810.705678829647, 10015.799684006279, 13394.799078013837, 13402.53091141825, 13405.932970335281, 13411.329433528284, 13412.0611557192, 6655.667216639149, 6977.0, 7002.929970700329, 7060.3292687194635, 7127.251638577978, 6414.454771344394, 6583.604983700993, 6796.286389928986, 6802.4217941474535, 6806.18325800907, 4166.479190101238, 4200.453940987713, 4226.050985767412, 4232.0, 4289.442372491556, 1784.8304411080926, 1811.3117015534012, 1816.8001519832808, 1839.8804077734903, 1860.2930886263134, 802.9397795165386, 826.2563692676518, 834.745402652197, 858.3433673239977, 889.5685592487638, 15455.149966751711, 15595.532134035997, 16054.75917861233, 16252.268647910872, 16965.066921319198, 783.925527074927, 799.17285609394, 800.1318569352318, 816.4611356504679, 823.9258466738022, 8710.520921349387, 8743.256823170103, 8912.242459390895, 9041.457512549268, 9472.100150485692, 334175.0, 341454.0, 343229.0, 343389.0, 344510.0, 75129.62435187899, 76444.0, 77707.22292777113, 79214.0, 79866.6006669963, 842.3303473738392, 864.2481041493814, 874.3092956564243, 875.7635438431715, 878.7188099808128, 14196.0, 14651.267436628339, 14919.0, 14948.701025979637, 15200.695986080438, 36097.63902361048, 36392.27215455729, 36456.27087458289, 36685.44971825478, 37535.249295014495, 49177.71830509463, 49801.501984981136, 50521.494785053146, 51059.74470127626, 51361.229581557054, 6523.575967562093, 6776.728930842711, 7154.785356439315, 7253.528520646352, 7478.70085196586, 6966.895496567454, 9616.894057183514, 9761.560729767036, 11683.357415342238, 11867.76264474723, 9598.848138223459, 9989.350692205275, 10592.099671527698, 10675.786484270426, 10770.407627580558, 10082.193424526167, 10085.243606729378, 10141.594336226468, 10163.136133428741, 10283.382997020202, 10107.241956853119, 10214.182865370902, 10239.897601024193, 10301.896981030093, 10405.785656809005, 6908.653463771598, 6956.756513522002, 6966.5471744336455, 7098.574085554883, 7145.21402645719, 8991.78610887548, 9747.756306092404, 9839.0, 9964.850527241952, 10184.796304074025], "value": 7792.004794250967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1202.2846406388207, 1331.1081575344522, 1364.5428781358244, 1382.198324971516, 1634.5586691593262, 5877.059670452729, 6740.258571557131, 7020.227774944752, 7035.824104397386, 7205.89191162133, 7029.402500787426, 7079.185893622235, 7089.574625522472, 7090.1846287676935, 7093.0, 1773.8314860088335, 1833.294181740033, 2054.9691754623655, 2089.2165437960766, 2091.362134548962, 2028.3509277031355, 2075.0247383729625, 2082.500199952016, 2087.112976984783, 2095.486605781578, 2055.085486958304, 2066.0392917293457, 2070.6686930091187, 2071.9171233150732, 2075.7612874519396, 2040.816326530612, 2049.9590008199752, 2061.907214175366, 2063.8039386258356, 2064.3910046536307, 1999.5800881814691, 2012.2353505667752, 2015.032784263547, 2019.1519561784087, 2028.3407892435018, 6859.451243900526, 6916.6541672916155, 6968.512204145712, 7063.788086357417, 7072.434205263567, 4230.957690423087, 4359.956400436018, 4363.629091527224, 4366.01764602965, 4368.5194628590825, 2638.261286839688, 2836.4752520783513, 2859.4709978654014, 2873.3534954635247, 2910.5634154876725, 840.1766269056359, 861.7888617288742, 879.3185281406896, 905.7826121730803, 910.6767097680295, 9229.53852307382, 9235.907640923571, 9261.44431334122, 9294.117058879396, 9303.674371397035, 3614.8192590370904, 3797.430385442206, 3847.9230415392094, 3911.198204368098, 3914.549826769901, 7913.0504339478475, 7919.049714034353, 7922.049354077548, 7924.445288829726, 7927.3658107351275, 4183.91632167355, 4184.497860256789, 4185.121124563876, 4186.623203911602, 4191.245575796386, 3413.675700808419, 3516.4197907345515, 3718.9628103719156, 3800.8859734208017, 3808.8857334280015, 3293.341331733615, 3313.801171929692, 3405.0465869556565, 3421.0421082097055, 3432.7940323580665, 1067.80779459698, 1089.841972913923, 1103.3159441146381, 1106.789709955106, 1107.213878146509, 665.7736369634348, 811.5130921447085, 823.2014945502852, 823.5882058970515, 850.1838235294064, 4710.317004034396, 4713.175194341045, 4757.619390448755, 4833.154198015333, 4979.800807967641, 402.52904102200495, 406.3660689324663, 412.2332461621382, 412.6410023279766, 415.08887990859773, 3259.885903993349, 3318.386098571772, 3361.7478689097925, 3365.8317084146183, 3402.795832250073, 7883.960580197062, 8290.875636865452, 8324.0, 8328.458650187718, 8381.874271885927, 8276.420650554403, 8280.213379728915, 8284.378671599654, 8285.461445006054, 8289.41974061822, 6250.593711408744, 6381.4575761059905, 6391.776287829996, 6430.8456667016135, 6433.710483028181, 4541.591256786905, 4617.699849509771, 4659.44086709597, 4691.413573303337, 4762.142814293322, 7588.089429268523, 7600.125985511737, 7649.0, 7682.539047657158, 7685.193054729173, 7535.321821036133, 7688.46180767341, 7697.692092316244, 7705.1524332324525, 7810.297073263434, 5459.07195776711, 5616.9047035827625, 6051.576389652682, 6130.478909292582, 6136.048912418582, 6402.231732192166, 6581.440577550962, 6621.1392518972225, 6767.0, 6768.0, 6005.909911351246, 6013.776579814245, 6107.0, 6169.938300616936, 6221.9377806221355, 6078.118672792509, 6086.6652334122045, 6123.785667501616, 6136.662483563268, 6148.569600127948, 5799.3040835100055, 5900.11498275245, 5908.527317814479, 5911.083782013795, 5915.940840591539, 4304.956950430581, 4375.234333991602, 4421.314696222091, 4469.44131983502, 4554.27131658933, 4453.153900758845, 4487.775611219361, 4492.438445194351, 4494.865154045384, 4631.745254011062], "value": 3703.172144075483, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.88543140028287, 283.6879432624115, 290.1440749788124, 290.47134215727374, 294.41411590934047, 4572.679912406133, 4758.405199350082, 4775.919531901925, 4801.975990120049, 4821.517848215177, 4587.1972404829185, 4689.788959496822, 4724.456687480941, 4726.03116361146, 4739.478657347698, 3769.9057523561855, 3778.414345776409, 3781.2815565042692, 3782.2813665403623, 3797.658210761031, 3592.1378869071364, 3722.008725331948, 3769.5853456119803, 3771.8868433947027, 3800.90497737556, 3659.048647351681, 3736.9252614947686, 3744.7191460640424, 3755.8685446009395, 3778.9622103778884, 3726.459663348799, 3736.7010639148803, 3743.681787048104, 3751.756135851161, 3765.4540091686827, 3315.8176300303476, 3320.0, 3322.2026713588807, 3324.335132973415, 3341.365140623274, 4426.535213802536, 4608.400907881987, 4652.74409907455, 4666.580007799281, 4674.602658774008, 4188.0, 4201.873943781691, 4206.831726730927, 4208.200441916057, 4216.575469105277, 4978.8755281118265, 4988.6757360771435, 4993.850184494471, 4994.950050499521, 5005.672362351487, 3306.206510437502, 3503.1767534629344, 3510.3681337359267, 3515.9648403515894, 3518.384282750534, 5142.945696132284, 5223.947760522422, 5232.241325007891, 5235.842924712265, 5248.527632513092, 3574.5174401455993, 3579.19468119671, 3583.8924832255075, 3588.5693716753635, 3591.6049234584175, 3604.711623070148, 3620.945685814715, 3624.7825130492256, 3628.6552777486095, 3630.546181727284, 3923.7253392262837, 4075.5109386873764, 4112.835486580503, 4170.643586373689, 4175.290200665894, 4936.975315123402, 4940.876478088005, 4950.381202349707, 4955.663794180093, 4956.0, 1154.9133814963911, 1179.3808250668467, 1179.5314833032758, 1200.8799120087922, 1212.8120141377924, 772.2277722277722, 789.5736302396756, 814.4095530740166, 817.6811043692965, 826.6321486938255, 4281.828726850891, 4295.312749960054, 4298.871033868989, 4347.934780978288, 4363.5564084113, 5010.298558201855, 5014.674046186986, 5041.319421878073, 5043.5460808527405, 5046.293518907282, 4233.089885674594, 4288.18524480354, 4291.527931927485, 4293.86212653647, 4316.762578058237, 2626.802989775736, 2638.670166229221, 2639.4721055788536, 2640.009996251406, 2644.206737978637, 4633.745144017112, 4641.837535686234, 4645.117427688729, 4669.276262179367, 4669.836555720533, 4961.578265847444, 5085.779412940868, 5217.3999990000875, 5310.946890531122, 5311.946880531223, 4845.092944233432, 4845.2732090185345, 4859.2954021666665, 4860.3681521401995, 4865.367502224759, 5175.896482070262, 5194.766235519335, 5202.505761952609, 5209.218617207449, 5229.189475631283, 4899.461059283547, 5037.92443113361, 5059.217430413184, 5068.873278168076, 5101.464346243591, 4857.1742803724155, 4865.70805751656, 4867.367242258625, 4869.3479890082435, 4875.24433712775, 3526.7178625710394, 3526.8486223404216, 3545.5036294919246, 3548.2371290172737, 3562.7684200526883, 4970.204767237225, 5143.897122057613, 5165.173572228426, 5177.585793136464, 5180.7409629517615, 5022.422421421583, 5032.446430892525, 5032.471590483093, 5038.672486288379, 5039.143345631325, 5009.323741294878, 5010.344517405781, 5011.774470148925, 5021.443352560718, 5033.0, 4740.241561350167, 4777.976110119566, 4853.466118726867, 4941.6293777966075, 4954.331165292783, 4907.950920490749, 5061.620378471555, 5072.365954255718, 5101.132807422673, 5102.377312108444, 4937.506249375034, 4953.628477864247, 4955.1576232039915, 4959.479254678206, 4975.5717411503065], "value": 3729.0288790441896, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.7004173017817, 631.5358211714392, 650.7917466410747, 706.6290197646235, 709.3261401668415, 11703.473343699532, 12076.094292928043, 12084.154109212359, 12096.33470159141, 12123.393830308494, 12087.879121208785, 12149.149559530837, 12151.878481215184, 12167.634970950863, 12251.754964900696, 603.4870360193844, 618.653554009756, 619.9783007594735, 747.3423387419091, 761.1095018827984, 623.3299203356387, 626.2297374229701, 739.4786675393834, 740.126650552348, 750.3172113376828, 804.8873157757919, 815.8776183572452, 815.8898548695939, 816.2694388522269, 816.6488409983705, 804.7746630943316, 805.5851236613149, 805.9516429014278, 806.1696452653777, 806.6531391501652, 468.72345316263227, 470.57648116694907, 569.1945896556368, 595.1816252652603, 595.4462350014485, 11024.062954648783, 11951.402429878472, 11976.58081967127, 12000.399980000964, 12001.699957501045, 11575.652730418102, 11851.0, 12144.696382590417, 12395.75208495834, 12510.687232819162, 9490.478023708692, 9632.855507167398, 10920.836187457195, 10932.289401188897, 11100.222984391095, 3954.209158168349, 3996.0609256824623, 4030.556638769733, 4056.9594304057173, 4127.545969943304, 47423.76288118572, 47903.52096479025, 47976.0, 48039.519604804205, 49138.0, 9504.95247523758, 9542.33203675736, 9550.856737148948, 9563.760905977406, 9598.520073996411, 16039.0, 16085.0, 16170.27233774483, 16209.189540523163, 16230.51308460748, 6085.726142323606, 6136.17161683176, 6168.845778855564, 6185.752569897244, 6208.161898143783, 3196.2329041029984, 3223.1942014496376, 3278.8360581970805, 3291.588551431071, 3319.302946381287, 2436.8903399347073, 2514.0, 2518.5592521309063, 2529.6964364276405, 2535.404180017694, 441.5496193882286, 450.293039927311, 454.59313914046743, 459.14827994071186, 462.56518872259966, 541.1720068295514, 541.8780774325745, 568.6815383385317, 568.9914651280151, 576.9278840144982, 6889.862202756017, 7222.386097181693, 7577.886331705029, 7693.192214817475, 7786.727464538714, 428.76846502888714, 536.8752463588776, 552.6104096611879, 563.9548836093103, 576.3602401334515, 3325.467925131967, 3346.682065203802, 3449.0170301463963, 3547.7693949893173, 3722.3858063419775, 39061.21877562433, 39392.409113863316, 45116.0, 58604.0, 68080.3191968074, 35494.64505354965, 37376.25247495035, 46808.76595617, 47725.76137119292, 54538.72730636401, 50135.24797127973, 50201.74899125481, 50204.74897625489, 50258.748706256236, 50879.49120508821, 7692.96128029414, 8523.190296921783, 8528.530930798866, 8662.610182541926, 8689.13108689126, 34663.653363466045, 35941.64058359383, 36305.81847090748, 37185.25629487449, 37823.0, 38433.42349864698, 39209.0, 40901.590984090966, 41977.790111049246, 45072.323915140645, 9887.0, 10031.648892288735, 10180.083792458418, 10428.061474467335, 10525.842112368473, 11874.168808183344, 12024.0, 12073.698157546132, 12450.0, 13005.609831705064, 11249.381284029458, 11388.202825802115, 11714.472848721658, 11883.0, 12402.441890114786, 10427.270091093551, 10483.580656773644, 11180.776384472427, 11846.229995050295, 11964.222325548812, 10705.304155229886, 11074.667759967213, 11205.775884482428, 11243.100551955988, 11446.94276528612, 8586.270167035871, 8671.523066231166, 8698.391112622056, 8846.424982376126, 9033.59348829291, 9522.0, 9807.411555306418, 9880.703578892644, 9887.832115123063, 9983.750406239902], "value": 5787.5394893350285, "unit": "score"}], "name": "dromaeo_css", "value": 6040.705182109499, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 117.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 129.0, 131.0, 174.0, 130.0, 132.0, 132.0, 128.0, 153.0, 131.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 164.0, 160.0, 163.0, 162.0, 162.0, 162.0, 163.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 112.0, 77.0, 77.0, 75.0, 78.0, 98.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 118.0, 83.0, 85.0, 84.0, 99.0, 85.0, 84.0, 106.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 119.0, 118.0, 118.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 148.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 64.0, 65.0, 64.0, 63.0, 63.0, 64.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 67.0, 67.0, 67.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 94.0, 85.0, 88.0, 86.0, 85.0, 84.0, 84.0, 90.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 143.0, 145.0, 140.0, 150.0, 138.0, 148.0, 189.0, 141.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 177.0, 179.0, 177.0, 187.0, 177.0, 176.0, 176.0, 181.0, 177.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 71.0, 69.0, 70.0, 70.0, 72.0, 70.0, 71.0, 69.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1562.2, "alertThreshold": 2.0}]} 18:57:30 INFO - Return code: 0 18:57:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:57:30 INFO - # TBPL SUCCESS # 18:57:30 INFO - Running post-action listener: _resource_record_post_action 18:57:30 INFO - [mozharness: 2016-11-28 02:57:30.781147Z] Finished run-tests step (success) 18:57:30 INFO - Running post-run listener: _resource_record_post_run 18:57:30 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391856128; Read time: 20; Write time: 268188 18:57:30 INFO - TinderboxPrint: CPU usage
13.0% 18:57:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 18:57:30 INFO - TinderboxPrint: I/O write bytes / time
391,856,128 / 268,188 18:57:30 INFO - TinderboxPrint: CPU idle
8,346.1 (86.9%) 18:57:30 INFO - TinderboxPrint: CPU user
1,205.5 (12.6%) 18:57:30 INFO - TinderboxPrint: Swap in / out
0 / 0 18:57:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99078144; Read time: 0; Write time: 66432 18:57:30 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 292777984; Read time: 0; Write time: 201756 18:57:31 INFO - Running post-run listener: _upload_blobber_files 18:57:31 INFO - Blob upload gear active. 18:57:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:57:31 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 18:57:31 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:57:31 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:57:31 INFO - (blobuploader) - INFO - Open directory for files ... 18:57:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:57:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:57:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:57:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:57:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:57:32 INFO - (blobuploader) - INFO - Done attempting. 18:57:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:57:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:57:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:57:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:57:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:57:33 INFO - (blobuploader) - INFO - Done attempting. 18:57:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:57:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:57:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:57:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:57:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:57:37 INFO - (blobuploader) - INFO - Done attempting. 18:57:37 INFO - (blobuploader) - INFO - Iteration through files over. 18:57:37 INFO - Return code: 0 18:57:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:57:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:57:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65ee5e8df4431d3b0347af65702afc79ab0835de2a92ccae67f9d2b673a73f3e74d7bffb52ce395c019e798928485379496799a9afbb3db1de70e0df945d1d50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/61c0de96e379bc2593a83befc617792e526979b3ee9e8a05119dd6ea5d8ae2dc9a4bda87a2d00e7ac9157f4730f47a672b5c85d338a67633fc0848b53d06df83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/630c4803c2117192c46ceea140183b6062e91c720e3a0b22dfe570c8c9230e1868e796777fb1c04983c9b27fe51f3dd4d98d4799ed13f0c513fbcf843541e005"} 18:57:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:57:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:57:37 INFO - Contents: 18:57:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65ee5e8df4431d3b0347af65702afc79ab0835de2a92ccae67f9d2b673a73f3e74d7bffb52ce395c019e798928485379496799a9afbb3db1de70e0df945d1d50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/61c0de96e379bc2593a83befc617792e526979b3ee9e8a05119dd6ea5d8ae2dc9a4bda87a2d00e7ac9157f4730f47a672b5c85d338a67633fc0848b53d06df83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/630c4803c2117192c46ceea140183b6062e91c720e3a0b22dfe570c8c9230e1868e796777fb1c04983c9b27fe51f3dd4d98d4799ed13f0c513fbcf843541e005"} 18:57:37 INFO - Running post-run listener: copy_logs_to_upload_dir 18:57:37 INFO - Copying logs to upload dir... 18:57:37 INFO - mkdir: /builds/slave/test/build/upload/logs 18:57:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.820505 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-11-27 18:57:36.203290) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 18:57:36.206987) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65ee5e8df4431d3b0347af65702afc79ab0835de2a92ccae67f9d2b673a73f3e74d7bffb52ce395c019e798928485379496799a9afbb3db1de70e0df945d1d50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/61c0de96e379bc2593a83befc617792e526979b3ee9e8a05119dd6ea5d8ae2dc9a4bda87a2d00e7ac9157f4730f47a672b5c85d338a67633fc0848b53d06df83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/630c4803c2117192c46ceea140183b6062e91c720e3a0b22dfe570c8c9230e1868e796777fb1c04983c9b27fe51f3dd4d98d4799ed13f0c513fbcf843541e005"} build_url:https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/fXJdT2zPRjmBvqIebgPUCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65ee5e8df4431d3b0347af65702afc79ab0835de2a92ccae67f9d2b673a73f3e74d7bffb52ce395c019e798928485379496799a9afbb3db1de70e0df945d1d50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/61c0de96e379bc2593a83befc617792e526979b3ee9e8a05119dd6ea5d8ae2dc9a4bda87a2d00e7ac9157f4730f47a672b5c85d338a67633fc0848b53d06df83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/630c4803c2117192c46ceea140183b6062e91c720e3a0b22dfe570c8c9230e1868e796777fb1c04983c9b27fe51f3dd4d98d4799ed13f0c513fbcf843541e005"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 18:57:36.232352) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:57:36.232716) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1480300263.329121-112316587 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 18:57:36.318391) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 18:57:36.318745) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 18:57:36.319009) ========= ========= Total master_lag: 0.18 =========