builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1475199671.3 results: success (0) buildid: 20160929153009 builduid: 283a4c6cefd14921bdc46ebf27be22b5 revision: 500496dfefccc73d1f87ff912563a8ee1afe8e91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.298132) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.298710) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.299028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.313867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.314191) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-29 18:41:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-09-29 18:41:11 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098228 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.426763) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.427124) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013908 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.476728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:11.477119) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 500496dfefccc73d1f87ff912563a8ee1afe8e91 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 500496dfefccc73d1f87ff912563a8ee1afe8e91 --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=a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-29 18:41:11,856 truncating revision to first 12 chars 2016-09-29 18:41:11,856 Setting DEBUG logging. 2016-09-29 18:41:11,856 attempt 1/10 2016-09-29 18:41:11,856 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/500496dfefcc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 18:41:12,231 unpacking tar archive at: fx-team-500496dfefcc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.737707 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:12.228535) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:12.228914) ========= script_repo_revision: 500496dfefccc73d1f87ff912563a8ee1afe8e91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:12.229370) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:12.229693) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 18:41:12.239668) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-29 18:41:12.240070) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:41:12 INFO - MultiFileLogger online at 20160929 18:41:12 in /builds/slave/test 18:41:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:41:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:41:12 INFO - {'append_to_log': False, 18:41:12 INFO - 'base_work_dir': '/builds/slave/test', 18:41:12 INFO - 'blob_upload_branch': 'Fx-Team', 18:41:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:41:12 INFO - 'branch': 'Fx-Team', 18:41:12 INFO - 'buildbot_json_path': 'buildprops.json', 18:41:12 INFO - 'config_files': ('talos/linux_config.py',), 18:41:12 INFO - 'default_actions': ('clobber', 18:41:12 INFO - 'read-buildbot-config', 18:41:12 INFO - 'download-and-extract', 18:41:12 INFO - 'populate-webroot', 18:41:12 INFO - 'create-virtualenv', 18:41:12 INFO - 'install', 18:41:12 INFO - 'run-tests'), 18:41:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:41:12 INFO - 'download_minidump_stackwalk': True, 18:41:12 INFO - 'download_symbols': 'ondemand', 18:41:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:41:12 INFO - 'tooltool.py': '/tools/tooltool.py', 18:41:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:41:12 INFO - '/tools/misc-python/virtualenv.py')}, 18:41:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:41:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:41:12 INFO - 'installer_path': 'installer.exe', 18:41:12 INFO - 'log_level': 'info', 18:41:12 INFO - 'log_name': 'talos', 18:41:12 INFO - 'log_to_console': True, 18:41:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:41:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:41:12 INFO - 'opt_config_files': (), 18:41:12 INFO - 'pip_index': False, 18:41:12 INFO - 'sps_profile': False, 18:41:12 INFO - 'sps_profile_interval': 0, 18:41:12 INFO - 'suite': 'dromaeojs', 18:41:12 INFO - 'system_bits': '32', 18:41:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:41:12 INFO - 'title': 'talos-linux64-ix-002', 18:41:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:41:12 INFO - 'use_talos_json': True, 18:41:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:41:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:41:12 INFO - 'work_dir': 'build'} 18:41:12 INFO - [mozharness: 2016-09-30 01:41:12.713629Z] Running clobber step. 18:41:12 INFO - Running pre-action listener: _resource_record_pre_action 18:41:12 INFO - Running main action method: clobber 18:41:12 INFO - rmtree: /builds/slave/test/build 18:41:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:41:13 INFO - Running post-action listener: _resource_record_post_action 18:41:13 INFO - [mozharness: 2016-09-30 01:41:13.723288Z] Finished clobber step (success) 18:41:13 INFO - [mozharness: 2016-09-30 01:41:13.723388Z] Running read-buildbot-config step. 18:41:13 INFO - Running pre-action listener: _resource_record_pre_action 18:41:13 INFO - Running main action method: read_buildbot_config 18:41:13 INFO - Using buildbot properties: 18:41:13 INFO - { 18:41:13 INFO - "project": "", 18:41:13 INFO - "product": "firefox", 18:41:13 INFO - "script_repo_revision": "production", 18:41:13 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:41:13 INFO - "repository": "", 18:41:13 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 18:41:13 INFO - "buildid": "20160929153009", 18:41:13 INFO - "pgo_build": "True", 18:41:13 INFO - "basedir": "/builds/slave/test", 18:41:13 INFO - "buildnumber": 1606, 18:41:13 INFO - "slavename": "talos-linux64-ix-002", 18:41:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:41:13 INFO - "platform": "ubuntu64_hw", 18:41:13 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:41:13 INFO - "branch": "fx-team", 18:41:13 INFO - "repo_path": "integration/fx-team", 18:41:13 INFO - "revision": "500496dfefccc73d1f87ff912563a8ee1afe8e91", 18:41:13 INFO - "stage_platform": "linux64-pgo", 18:41:13 INFO - "builduid": "283a4c6cefd14921bdc46ebf27be22b5", 18:41:13 INFO - "slavebuilddir": "test" 18:41:13 INFO - } 18:41:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 18:41:13 INFO - Running post-action listener: _resource_record_post_action 18:41:13 INFO - [mozharness: 2016-09-30 01:41:13.725835Z] Finished read-buildbot-config step (success) 18:41:13 INFO - [mozharness: 2016-09-30 01:41:13.725923Z] Running download-and-extract step. 18:41:13 INFO - Running pre-action listener: _resource_record_pre_action 18:41:13 INFO - Running main action method: download_and_extract 18:41:13 INFO - mkdir: /builds/slave/test/build/tests 18:41:13 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:41:13 INFO - trying https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:41:13 INFO - Downloading https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:41:14 INFO - Downloaded 1361 bytes. 18:41:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:41:14 INFO - Using the following test package requirements: 18:41:14 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:41:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:41:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:41:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'jsshell-linux-x86_64.zip'], 18:41:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:41:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:41:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:41:14 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:41:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:41:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:41:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:41:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:41:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:41:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:41:14 INFO - Fetch https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:41:14 INFO - Http code: 200 18:41:14 INFO - Content-Encoding: None 18:41:14 INFO - Content-Type: application/zip 18:41:14 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 18:41:14 INFO - x-amz-cf-id: k_5i5qnUI0x7D6w2hzGO-JDagk_6i7DJRHRB2cmO5lgUp-AhTe5AOw== 18:41:14 INFO - x-amz-version-id: eJdfmGtTMpE3sa1OE2Y4SW4k8zwSF7WV 18:41:14 INFO - x-cache: Hit from cloudfront 18:41:15 INFO - Expected file size: 21988793 18:41:15 INFO - Obtained file size: 21988793 18:41:19 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:41:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:41:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:41:19 INFO - Fetch https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:41:19 INFO - Http code: 200 18:41:19 INFO - Content-Encoding: None 18:41:19 INFO - Content-Type: application/zip 18:41:19 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 18:41:19 INFO - x-amz-cf-id: gkE1vdWoaAEimlFVgEKD_nTOCOzMI0e_0-uxJ48sVTl23QXKAy4V9A== 18:41:19 INFO - x-amz-version-id: eJdfmGtTMpE3sa1OE2Y4SW4k8zwSF7WV 18:41:19 INFO - x-cache: Hit from cloudfront 18:41:20 INFO - Expected file size: 21988793 18:41:20 INFO - Obtained file size: 21988793 18:41:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 18:41:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:41:24 INFO - Fetch https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:41:25 INFO - Http code: 200 18:41:25 INFO - Content-Encoding: None 18:41:25 INFO - Content-Type: application/zip 18:41:25 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 18:41:25 INFO - x-amz-cf-id: DrxGMQHGFbHC64z2FiXmnCaArujIC8ac6WyRZArOZaxCVj1vaoDSNg== 18:41:25 INFO - x-amz-version-id: z248qwQsmPapCtjbWA6eQXjNl6GeB75X 18:41:25 INFO - x-cache: Hit from cloudfront 18:41:25 INFO - Expected file size: 14414067 18:41:25 INFO - Obtained file size: 14414067 18:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:25 INFO - trying https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:41:25 INFO - Downloading https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:41:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:41:26 INFO - Downloaded 59716751 bytes. 18:41:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:41:26 INFO - mkdir: /builds/slave/test/properties 18:41:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:41:26 INFO - Writing to file /builds/slave/test/properties/build_url 18:41:26 INFO - Contents: 18:41:26 INFO - build_url:https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:41:27 INFO - Running post-action listener: _resource_record_post_action 18:41:27 INFO - Running post-action listener: set_extra_try_arguments 18:41:27 INFO - [mozharness: 2016-09-30 01:41:27.393195Z] Finished download-and-extract step (success) 18:41:27 INFO - [mozharness: 2016-09-30 01:41:27.393459Z] Running populate-webroot step. 18:41:27 INFO - Running pre-action listener: _resource_record_pre_action 18:41:27 INFO - Running main action method: populate_webroot 18:41:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:41:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:41:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:41:27 INFO - '--tppagecycles', 18:41:27 INFO - '7'], 18:41:27 INFO - 'tests': ['tsvgm']}}, 18:41:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:41:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:41:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:41:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:41:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:41:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:41:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['damp', 'tps']}, 18:41:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'tests': ['damp', 'tps']}, 18:41:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['dromaeo_dom']}, 18:41:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:41:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['basic_compositor_video']}, 18:41:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:41:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['a11yr', 18:41:27 INFO - 'ts_paint', 18:41:27 INFO - 'tpaint', 18:41:27 INFO - 'sessionrestore', 18:41:27 INFO - 'sessionrestore_no_auto_restore', 18:41:27 INFO - 'tabpaint']}, 18:41:27 INFO - 'other-e10s': {'tests': ['a11yr', 18:41:27 INFO - 'ts_paint', 18:41:27 INFO - 'tpaint', 18:41:27 INFO - 'sessionrestore', 18:41:27 INFO - 'sessionrestore_no_auto_restore', 18:41:27 INFO - 'tabpaint']}, 18:41:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:41:27 INFO - 'ts_paint', 18:41:27 INFO - 'tpaint', 18:41:27 INFO - 'sessionrestore', 18:41:27 INFO - 'sessionrestore_no_auto_restore', 18:41:27 INFO - 'tabpaint']}, 18:41:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:41:27 INFO - 'ts_paint', 18:41:27 INFO - 'tpaint', 18:41:27 INFO - 'sessionrestore', 18:41:27 INFO - 'sessionrestore_no_auto_restore', 18:41:27 INFO - 'tabpaint']}, 18:41:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['a11yr', 18:41:27 INFO - 'ts_paint', 18:41:27 INFO - 'tpaint', 18:41:27 INFO - 'sessionrestore', 18:41:27 INFO - 'sessionrestore_no_auto_restore', 18:41:27 INFO - 'tabpaint']}, 18:41:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['a11yr', 18:41:27 INFO - 'ts_paint', 18:41:27 INFO - 'tpaint', 18:41:27 INFO - 'sessionrestore', 18:41:27 INFO - 'sessionrestore_no_auto_restore', 18:41:27 INFO - 'tabpaint']}, 18:41:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['tsvgx', 18:41:27 INFO - 'tsvgr_opacity', 18:41:27 INFO - 'tart', 18:41:27 INFO - 'tscrollx', 18:41:27 INFO - 'cart']}, 18:41:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:41:27 INFO - 'tsvgr_opacity', 18:41:27 INFO - 'tart', 18:41:27 INFO - 'tscrollx', 18:41:27 INFO - 'cart']}, 18:41:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'talos_options': ['--disable-e10s'], 18:41:27 INFO - 'tests': ['tp5o']}, 18:41:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'tests': ['tp5o']}, 18:41:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'talos_options': ['--disable-e10s', 18:41:27 INFO - '--xperf_path', 18:41:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:41:27 INFO - 'tests': ['tp5n']}, 18:41:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:41:27 INFO - 'talos_options': ['--xperf_path', 18:41:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:41:27 INFO - 'tests': ['tp5n']}}, 18:41:27 INFO - 'talos.zip': {'path': '', 18:41:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:41:27 INFO - Running post-action listener: _resource_record_post_action 18:41:27 INFO - [mozharness: 2016-09-30 01:41:27.409266Z] Finished populate-webroot step (success) 18:41:27 INFO - [mozharness: 2016-09-30 01:41:27.409354Z] Running create-virtualenv step. 18:41:27 INFO - Running pre-action listener: _resource_record_pre_action 18:41:27 INFO - Running main action method: create_virtualenv 18:41:27 INFO - Creating virtualenv /builds/slave/test/build/venv 18:41:27 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:41:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:41:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:41:27 INFO - Using real prefix '/usr' 18:41:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:41:28 INFO - Installing distribute.............................................................................................................................................................................................done. 18:41:30 INFO - Installing pip.................done. 18:41:30 INFO - Return code: 0 18:41:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:41:30 INFO - Reading from file tmpfile_stdout 18:41:30 INFO - Output received: 18:41:30 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:41:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:30 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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:30 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:41:30 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:41:30 INFO - Using env: {'DISPLAY': ':0', 18:41:30 INFO - 'HOME': '/home/cltbld', 18:41:30 INFO - 'LANG': 'en_US.UTF-8', 18:41:30 INFO - 'LANGUAGE': 'en_US:en', 18:41:30 INFO - 'LOGNAME': 'cltbld', 18:41:30 INFO - 'MAIL': '/var/mail/cltbld', 18:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:30 INFO - 'MOZ_NO_REMOTE': '1', 18:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:30 INFO - 'NO_EM_RESTART': '1', 18:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:30 INFO - 'PWD': '/builds/slave/test', 18:41:30 INFO - 'SHELL': '/bin/bash', 18:41:30 INFO - 'SHLVL': '1', 18:41:30 INFO - 'TERM': 'linux', 18:41:30 INFO - 'TMOUT': '86400', 18:41:30 INFO - 'USER': 'cltbld', 18:41:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:30 INFO - '_': '/tools/buildbot/bin/python'} 18:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:30 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:41:30 INFO - Cleaning up... 18:41:30 INFO - Return code: 0 18:41:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:30 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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:30 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:41:30 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:41:30 INFO - Using env: {'DISPLAY': ':0', 18:41:30 INFO - 'HOME': '/home/cltbld', 18:41:30 INFO - 'LANG': 'en_US.UTF-8', 18:41:30 INFO - 'LANGUAGE': 'en_US:en', 18:41:30 INFO - 'LOGNAME': 'cltbld', 18:41:30 INFO - 'MAIL': '/var/mail/cltbld', 18:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:30 INFO - 'MOZ_NO_REMOTE': '1', 18:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:30 INFO - 'NO_EM_RESTART': '1', 18:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:30 INFO - 'PWD': '/builds/slave/test', 18:41:30 INFO - 'SHELL': '/bin/bash', 18:41:30 INFO - 'SHLVL': '1', 18:41:30 INFO - 'TERM': 'linux', 18:41:30 INFO - 'TMOUT': '86400', 18:41:30 INFO - 'USER': 'cltbld', 18:41:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:30 INFO - '_': '/tools/buildbot/bin/python'} 18:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:30 INFO - Downloading/unpacking psutil>=3.1.1 18:41:30 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:41:30 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:41:30 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:41:30 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:41:30 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:41:30 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:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:41:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:41:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:41:32 INFO - Installing collected packages: psutil 18:41:32 INFO - Running setup.py install for psutil 18:41:32 INFO - building 'psutil._psutil_linux' extension 18:41:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:41:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:41:32 INFO - building 'psutil._psutil_posix' extension 18:41:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:41:33 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:41:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:41:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:41:33 INFO - Successfully installed psutil 18:41:33 INFO - Cleaning up... 18:41:33 INFO - Return code: 0 18:41:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:41:33 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:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:33 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:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:33 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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:33 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:41:33 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:41:33 INFO - Using env: {'DISPLAY': ':0', 18:41:33 INFO - 'HOME': '/home/cltbld', 18:41:33 INFO - 'LANG': 'en_US.UTF-8', 18:41:33 INFO - 'LANGUAGE': 'en_US:en', 18:41:33 INFO - 'LOGNAME': 'cltbld', 18:41:33 INFO - 'MAIL': '/var/mail/cltbld', 18:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:33 INFO - 'MOZ_NO_REMOTE': '1', 18:41:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:33 INFO - 'NO_EM_RESTART': '1', 18:41:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:33 INFO - 'PWD': '/builds/slave/test', 18:41:33 INFO - 'SHELL': '/bin/bash', 18:41:33 INFO - 'SHLVL': '1', 18:41:33 INFO - 'TERM': 'linux', 18:41:33 INFO - 'TMOUT': '86400', 18:41:33 INFO - 'USER': 'cltbld', 18:41:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:33 INFO - '_': '/tools/buildbot/bin/python'} 18:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:41:33 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:41:33 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:41:33 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:41:33 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:41:33 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:41:33 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:41:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:41:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:41:35 INFO - Installing collected packages: mozsystemmonitor 18:41:35 INFO - Running setup.py install for mozsystemmonitor 18:41:35 INFO - Successfully installed mozsystemmonitor 18:41:35 INFO - Cleaning up... 18:41:35 INFO - Return code: 0 18:41:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:41:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:41:35 INFO - Using env: {'DISPLAY': ':0', 18:41:35 INFO - 'HOME': '/home/cltbld', 18:41:35 INFO - 'LANG': 'en_US.UTF-8', 18:41:35 INFO - 'LANGUAGE': 'en_US:en', 18:41:35 INFO - 'LOGNAME': 'cltbld', 18:41:35 INFO - 'MAIL': '/var/mail/cltbld', 18:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:35 INFO - 'MOZ_NO_REMOTE': '1', 18:41:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:35 INFO - 'NO_EM_RESTART': '1', 18:41:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:35 INFO - 'PWD': '/builds/slave/test', 18:41:35 INFO - 'SHELL': '/bin/bash', 18:41:35 INFO - 'SHLVL': '1', 18:41:35 INFO - 'TERM': 'linux', 18:41:35 INFO - 'TMOUT': '86400', 18:41:35 INFO - 'USER': 'cltbld', 18:41:35 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:35 INFO - '_': '/tools/buildbot/bin/python'} 18:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:35 INFO - Downloading/unpacking jsonschema==2.5.1 18:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:41:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:37 INFO - Downloading functools32-3.2.3-2.tar.gz 18:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:41:37 INFO - warning: no files found matching '*.txt' 18:41:37 INFO - no previously-included directories found matching 'build' 18:41:37 INFO - no previously-included directories found matching 'dist' 18:41:37 INFO - no previously-included directories found matching '.git*' 18:41:37 INFO - Installing collected packages: jsonschema, functools32 18:41:37 INFO - Running setup.py install for functools32 18:41:38 INFO - warning: no files found matching '*.txt' 18:41:38 INFO - no previously-included directories found matching 'build' 18:41:38 INFO - no previously-included directories found matching 'dist' 18:41:38 INFO - no previously-included directories found matching '.git*' 18:41:38 INFO - Successfully installed jsonschema functools32 18:41:38 INFO - Cleaning up... 18:41:38 INFO - Return code: 0 18:41:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:41:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:41:38 INFO - Using env: {'DISPLAY': ':0', 18:41:38 INFO - 'HOME': '/home/cltbld', 18:41:38 INFO - 'LANG': 'en_US.UTF-8', 18:41:38 INFO - 'LANGUAGE': 'en_US:en', 18:41:38 INFO - 'LOGNAME': 'cltbld', 18:41:38 INFO - 'MAIL': '/var/mail/cltbld', 18:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:38 INFO - 'MOZ_NO_REMOTE': '1', 18:41:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:38 INFO - 'NO_EM_RESTART': '1', 18:41:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:38 INFO - 'PWD': '/builds/slave/test', 18:41:38 INFO - 'SHELL': '/bin/bash', 18:41:38 INFO - 'SHLVL': '1', 18:41:38 INFO - 'TERM': 'linux', 18:41:38 INFO - 'TMOUT': '86400', 18:41:38 INFO - 'USER': 'cltbld', 18:41:38 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:38 INFO - '_': '/tools/buildbot/bin/python'} 18:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:41:38 INFO - Cleaning up... 18:41:38 INFO - Return code: 0 18:41:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:41:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:41:38 INFO - Using env: {'DISPLAY': ':0', 18:41:38 INFO - 'HOME': '/home/cltbld', 18:41:38 INFO - 'LANG': 'en_US.UTF-8', 18:41:38 INFO - 'LANGUAGE': 'en_US:en', 18:41:38 INFO - 'LOGNAME': 'cltbld', 18:41:38 INFO - 'MAIL': '/var/mail/cltbld', 18:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:38 INFO - 'MOZ_NO_REMOTE': '1', 18:41:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:38 INFO - 'NO_EM_RESTART': '1', 18:41:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:38 INFO - 'PWD': '/builds/slave/test', 18:41:38 INFO - 'SHELL': '/bin/bash', 18:41:38 INFO - 'SHLVL': '1', 18:41:38 INFO - 'TERM': 'linux', 18:41:38 INFO - 'TMOUT': '86400', 18:41:38 INFO - 'USER': 'cltbld', 18:41:38 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:38 INFO - '_': '/tools/buildbot/bin/python'} 18:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:38 INFO - Downloading/unpacking blobuploader==1.2.4 18:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:40 INFO - Downloading blobuploader-1.2.4.tar.gz 18:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:41:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:41:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:41 INFO - Downloading docopt-0.6.1.tar.gz 18:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:41:41 INFO - Installing collected packages: blobuploader, requests, docopt 18:41:41 INFO - Running setup.py install for blobuploader 18:41:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:41:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:41:41 INFO - Running setup.py install for requests 18:41:41 INFO - Running setup.py install for docopt 18:41:41 INFO - Successfully installed blobuploader requests docopt 18:41:41 INFO - Cleaning up... 18:41:41 INFO - Return code: 0 18:41:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:41:41 INFO - Using env: {'DISPLAY': ':0', 18:41:41 INFO - 'HOME': '/home/cltbld', 18:41:41 INFO - 'LANG': 'en_US.UTF-8', 18:41:41 INFO - 'LANGUAGE': 'en_US:en', 18:41:41 INFO - 'LOGNAME': 'cltbld', 18:41:41 INFO - 'MAIL': '/var/mail/cltbld', 18:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:41 INFO - 'MOZ_NO_REMOTE': '1', 18:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:41 INFO - 'NO_EM_RESTART': '1', 18:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:41 INFO - 'PWD': '/builds/slave/test', 18:41:41 INFO - 'SHELL': '/bin/bash', 18:41:41 INFO - 'SHLVL': '1', 18:41:41 INFO - 'TERM': 'linux', 18:41:41 INFO - 'TMOUT': '86400', 18:41:41 INFO - 'USER': 'cltbld', 18:41:41 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:41 INFO - '_': '/tools/buildbot/bin/python'} 18:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:41:42 INFO - Running setup.py (path:/tmp/pip-FMHuJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:41:42 INFO - Running setup.py (path:/tmp/pip-RZH8Zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:41:42 INFO - Running setup.py (path:/tmp/pip-g9aqS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:41:42 INFO - Running setup.py (path:/tmp/pip-axRBmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:41:42 INFO - Running setup.py (path:/tmp/pip-knswCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:41:42 INFO - Running setup.py (path:/tmp/pip-1B7pXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:41:42 INFO - Running setup.py (path:/tmp/pip-h8PhNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:41:42 INFO - Running setup.py (path:/tmp/pip-8IAPsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:41:42 INFO - Running setup.py (path:/tmp/pip-zL5Yhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:41:42 INFO - Running setup.py (path:/tmp/pip-yXq5dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:41:42 INFO - Running setup.py (path:/tmp/pip-fzN4Ri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:41:42 INFO - Running setup.py (path:/tmp/pip-T2OpJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:41:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:41:42 INFO - Running setup.py (path:/tmp/pip-b2LWGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:41:43 INFO - Running setup.py (path:/tmp/pip-1dfrmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:43 INFO - Running setup.py (path:/tmp/pip-fxd6Dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:41:43 INFO - Running setup.py (path:/tmp/pip-LzgjZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:41:43 INFO - Running setup.py (path:/tmp/pip-NVJKgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:41:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:41:43 INFO - Running setup.py install for manifestparser 18:41:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:41:43 INFO - Running setup.py install for mozcrash 18:41:43 INFO - Running setup.py install for mozdebug 18:41:43 INFO - Running setup.py install for mozdevice 18:41:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:41:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:41:43 INFO - Running setup.py install for mozfile 18:41:43 INFO - Running setup.py install for mozhttpd 18:41:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:41:43 INFO - Running setup.py install for mozinfo 18:41:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Running setup.py install for mozInstall 18:41:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Running setup.py install for mozleak 18:41:44 INFO - Running setup.py install for mozlog 18:41:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Running setup.py install for moznetwork 18:41:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Running setup.py install for mozprocess 18:41:44 INFO - Running setup.py install for mozprofile 18:41:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Running setup.py install for mozrunner 18:41:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:41:44 INFO - Running setup.py install for mozscreenshot 18:41:44 INFO - Running setup.py install for moztest 18:41:44 INFO - Running setup.py install for mozversion 18:41:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:41:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:41:45 INFO - Cleaning up... 18:41:45 INFO - Return code: 0 18:41:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:41:45 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:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:45 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:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:45 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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:45 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:41:45 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:41:45 INFO - Using env: {'DISPLAY': ':0', 18:41:45 INFO - 'HOME': '/home/cltbld', 18:41:45 INFO - 'LANG': 'en_US.UTF-8', 18:41:45 INFO - 'LANGUAGE': 'en_US:en', 18:41:45 INFO - 'LOGNAME': 'cltbld', 18:41:45 INFO - 'MAIL': '/var/mail/cltbld', 18:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:45 INFO - 'MOZ_NO_REMOTE': '1', 18:41:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:45 INFO - 'NO_EM_RESTART': '1', 18:41:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:45 INFO - 'PWD': '/builds/slave/test', 18:41:45 INFO - 'SHELL': '/bin/bash', 18:41:45 INFO - 'SHLVL': '1', 18:41:45 INFO - 'TERM': 'linux', 18:41:45 INFO - 'TMOUT': '86400', 18:41:45 INFO - 'USER': 'cltbld', 18:41:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:45 INFO - '_': '/tools/buildbot/bin/python'} 18:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:41:45 INFO - Running setup.py (path:/tmp/pip-dvmL9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:41:45 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:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:41:45 INFO - Running setup.py (path:/tmp/pip-ZjrXpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:41:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:41:45 INFO - Running setup.py (path:/tmp/pip-yGoen6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:41:45 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:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:41:45 INFO - Running setup.py (path:/tmp/pip-5fSnxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:41:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:41:45 INFO - Running setup.py (path:/tmp/pip-Eagq_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:41:45 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:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:41:45 INFO - Running setup.py (path:/tmp/pip-yZnDhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:41:45 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:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:41:45 INFO - Running setup.py (path:/tmp/pip-f02KSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:41:46 INFO - Running setup.py (path:/tmp/pip-VdFfq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:41:46 INFO - Running setup.py (path:/tmp/pip-Yw_lx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:41:46 INFO - Running setup.py (path:/tmp/pip-bHQRnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:41:46 INFO - Running setup.py (path:/tmp/pip-32Nfqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:41:46 INFO - Running setup.py (path:/tmp/pip-y7lTjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:41:46 INFO - Running setup.py (path:/tmp/pip-cdtl6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:41:46 INFO - Running setup.py (path:/tmp/pip-qmVjGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:46 INFO - Running setup.py (path:/tmp/pip-2Iup82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:41:46 INFO - Running setup.py (path:/tmp/pip-rgoKn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:41:46 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:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:41:46 INFO - Running setup.py (path:/tmp/pip-8P_3Ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:41:46 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:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:41:46 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:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:41:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:48 INFO - Downloading blessings-1.6.tar.gz 18:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:41:48 INFO - Installing collected packages: blessings 18:41:48 INFO - Running setup.py install for blessings 18:41:48 INFO - Successfully installed blessings 18:41:48 INFO - Cleaning up... 18:41:48 INFO - Return code: 0 18:41:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:41:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:41:49 INFO - Reading from file tmpfile_stdout 18:41:49 INFO - Current package versions: 18:41:49 INFO - argparse == 1.2.1 18:41:49 INFO - blessings == 1.6 18:41:49 INFO - blobuploader == 1.2.4 18:41:49 INFO - docopt == 0.6.1 18:41:49 INFO - functools32 == 3.2.3-2 18:41:49 INFO - jsonschema == 2.5.1 18:41:49 INFO - manifestparser == 1.1 18:41:49 INFO - mozInstall == 1.12 18:41:49 INFO - mozcrash == 0.17 18:41:49 INFO - mozdebug == 0.1 18:41:49 INFO - mozdevice == 0.48 18:41:49 INFO - mozfile == 1.2 18:41:49 INFO - mozhttpd == 0.7 18:41:49 INFO - mozinfo == 0.9 18:41:49 INFO - mozleak == 0.1 18:41:49 INFO - mozlog == 3.4 18:41:49 INFO - moznetwork == 0.27 18:41:49 INFO - mozprocess == 0.23 18:41:49 INFO - mozprofile == 0.28 18:41:49 INFO - mozrunner == 6.12 18:41:49 INFO - mozscreenshot == 0.1 18:41:49 INFO - mozsystemmonitor == 0.3 18:41:49 INFO - moztest == 0.7 18:41:49 INFO - mozversion == 1.4 18:41:49 INFO - psutil == 3.1.1 18:41:49 INFO - requests == 1.2.3 18:41:49 INFO - wsgiref == 0.1.2 18:41:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:41:49 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:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:49 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:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:49 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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:49 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:41:49 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:41:49 INFO - Using env: {'DISPLAY': ':0', 18:41:49 INFO - 'HOME': '/home/cltbld', 18:41:49 INFO - 'LANG': 'en_US.UTF-8', 18:41:49 INFO - 'LANGUAGE': 'en_US:en', 18:41:49 INFO - 'LOGNAME': 'cltbld', 18:41:49 INFO - 'MAIL': '/var/mail/cltbld', 18:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:49 INFO - 'MOZ_NO_REMOTE': '1', 18:41:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:49 INFO - 'NO_EM_RESTART': '1', 18:41:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:49 INFO - 'PWD': '/builds/slave/test', 18:41:49 INFO - 'SHELL': '/bin/bash', 18:41:49 INFO - 'SHLVL': '1', 18:41:49 INFO - 'TERM': 'linux', 18:41:49 INFO - 'TMOUT': '86400', 18:41:49 INFO - 'USER': 'cltbld', 18:41:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:49 INFO - '_': '/tools/buildbot/bin/python'} 18:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:49 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:41:49 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:41:49 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:41:49 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:41:49 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:41:49 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:41:49 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:41:49 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:41:49 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:41:49 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:41:49 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:41:49 INFO - Cleaning up... 18:41:49 INFO - Return code: 0 18:41:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:41:49 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:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:49 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:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:49 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 0x7f85a4648f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18b58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19817b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', '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:41:49 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:41:49 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:41:49 INFO - Using env: {'DISPLAY': ':0', 18:41:49 INFO - 'HOME': '/home/cltbld', 18:41:49 INFO - 'LANG': 'en_US.UTF-8', 18:41:49 INFO - 'LANGUAGE': 'en_US:en', 18:41:49 INFO - 'LOGNAME': 'cltbld', 18:41:49 INFO - 'MAIL': '/var/mail/cltbld', 18:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:49 INFO - 'MOZ_NO_REMOTE': '1', 18:41:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:41:49 INFO - 'NO_EM_RESTART': '1', 18:41:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:49 INFO - 'PWD': '/builds/slave/test', 18:41:49 INFO - 'SHELL': '/bin/bash', 18:41:49 INFO - 'SHLVL': '1', 18:41:49 INFO - 'TERM': 'linux', 18:41:49 INFO - 'TMOUT': '86400', 18:41:49 INFO - 'USER': 'cltbld', 18:41:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:49 INFO - '_': '/tools/buildbot/bin/python'} 18:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:41:49 INFO - Cleaning up... 18:41:49 INFO - Return code: 0 18:41:49 INFO - Running post-action listener: _resource_record_post_action 18:41:49 INFO - Running post-action listener: _start_resource_monitoring 18:41:49 INFO - Starting resource monitoring. 18:41:49 INFO - [mozharness: 2016-09-30 01:41:49.607261Z] Finished create-virtualenv step (success) 18:41:49 INFO - [mozharness: 2016-09-30 01:41:49.607780Z] Running install step. 18:41:49 INFO - Running pre-action listener: _resource_record_pre_action 18:41:49 INFO - Running main action method: install 18:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:41:49 INFO - Reading from file tmpfile_stdout 18:41:49 INFO - Detecting whether we're running mozinstall >=1.0... 18:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:41:49 INFO - Reading from file tmpfile_stdout 18:41:49 INFO - Output received: 18:41:49 INFO - Usage: mozinstall [options] installer 18:41:49 INFO - Options: 18:41:49 INFO - -h, --help show this help message and exit 18:41:49 INFO - -d DEST, --destination=DEST 18:41:49 INFO - Directory to install application into. [default: 18:41:49 INFO - "/builds/slave/test"] 18:41:49 INFO - --app=APP Application being installed. [default: firefox] 18:41:49 INFO - mkdir: /builds/slave/test/build/application 18:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:42:06 INFO - Reading from file tmpfile_stdout 18:42:06 INFO - Output received: 18:42:06 INFO - /builds/slave/test/build/application/firefox/firefox 18:42:06 INFO - Running post-action listener: _resource_record_post_action 18:42:06 INFO - [mozharness: 2016-09-30 01:42:06.873619Z] Finished install step (success) 18:42:06 INFO - [mozharness: 2016-09-30 01:42:06.873868Z] Running run-tests step. 18:42:06 INFO - Running pre-action listener: _resource_record_pre_action 18:42:06 INFO - Running main action method: run_tests 18:42:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:42:06 INFO - Python 2.7.3 18:42:06 INFO - Return code: 0 18:42:06 INFO - grabbing minidump binary from tooltool 18:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:42:06 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 0x1a10790>, '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 0x1a11690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a11b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:42:06 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:42:06 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:42:06 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:42:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:42:06 INFO - Return code: 0 18:42:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:42:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:42:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:42:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:42:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:42:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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:42:06 INFO - Using env: {'DISPLAY': ':0', 18:42:06 INFO - 'HOME': '/home/cltbld', 18:42:06 INFO - 'LANG': 'en_US.UTF-8', 18:42:06 INFO - 'LANGUAGE': 'en_US:en', 18:42:06 INFO - 'LOGNAME': 'cltbld', 18:42:06 INFO - 'MAIL': '/var/mail/cltbld', 18:42:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:42:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:06 INFO - 'MOZ_NO_REMOTE': '1', 18:42:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:42:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:06 INFO - 'NO_EM_RESTART': '1', 18:42:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:06 INFO - 'PWD': '/builds/slave/test', 18:42:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:42:06 INFO - 'SHELL': '/bin/bash', 18:42:06 INFO - 'SHLVL': '1', 18:42:06 INFO - 'TERM': 'linux', 18:42:06 INFO - 'TMOUT': '86400', 18:42:06 INFO - 'USER': 'cltbld', 18:42:06 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197', 18:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:06 INFO - '_': '/tools/buildbot/bin/python'} 18:42:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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:42:07 INFO - mozversion application_buildid: 20160929153009 18:42:07 INFO - mozversion application_changeset: 500496dfefccc73d1f87ff912563a8ee1afe8e91 18:42:07 INFO - mozversion application_display_name: Nightly 18:42:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:42:07 INFO - mozversion application_name: Firefox 18:42:07 INFO - mozversion application_remotingname: firefox 18:42:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:42:07 INFO - mozversion application_vendor: Mozilla 18:42:07 INFO - mozversion application_version: 52.0a1 18:42:07 INFO - mozversion platform_buildid: 20160929153009 18:42:07 INFO - mozversion platform_changeset: 500496dfefccc73d1f87ff912563a8ee1afe8e91 18:42:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:42:07 INFO - mozversion platform_version: 52.0a1 18:42:07 INFO - using testdate: 1475199727 18:42:07 INFO - actual date: 1475199727 18:42:07 INFO - starting webserver on 'localhost:49477' 18:42:07 INFO - SUITE-START | Running 2 tests 18:42:07 INFO - TEST-START | dromaeo_css 18:42:07 INFO - operating with platform_type : linux_ 18:42:07 INFO - Initialising browser for dromaeo_css test... 18:42:07 INFO - TEST-INFO | started process 21949 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpel7X0v/profile http://localhost:49477/getInfo.html) 18:42:11 INFO - PROCESS | 21949 | __metrics Screen width/height:1600/1200 18:42:11 INFO - PROCESS | 21949 | colorDepth:24 18:42:11 INFO - PROCESS | 21949 | Browser inner width/height: 1024/697 18:42:11 INFO - PROCESS | 21949 | __metrics 18:42:12 INFO - PROCESS | 21949 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:42:12 INFO - PROCESS | 21949 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:42:12 INFO - PROCESS | 21949 | console.error: PushService: 18:42:12 INFO - PROCESS | 21949 | stateChangeProcessEnqueue: Error transitioning state 18:42:12 INFO - PROCESS | 21949 | UnknownError 18:42:13 INFO - TEST-INFO | 21949: exit 0 18:42:13 INFO - Browser initialized. 18:42:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:42:13 INFO - TEST-INFO | started process 22030 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpel7X0v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:25 INFO - PROCESS | 22030 | RSS: Main: 173674496 18:42:25 INFO - PROCESS | 22030 | 18:43:17 INFO - PROCESS | 22030 | 1475199797015 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:45:26 INFO - PROCESS | 22030 | Cycle 1(1): loaded http://localhost:49477/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49477/tests/dromaeo/cssquery-ext.html) 18:45:27 INFO - PROCESS | 22030 | RSS: Main: 325926912 18:45:27 INFO - PROCESS | 22030 | 18:48:29 INFO - PROCESS | 22030 | Cycle 1(1): loaded http://localhost:49477/tests/dromaeo/cssquery-ext.html (next: http://localhost:49477/tests/dromaeo/cssquery-jquery.html) 18:48:30 INFO - PROCESS | 22030 | RSS: Main: 271482880 18:48:30 INFO - PROCESS | 22030 | 18:51:32 INFO - PROCESS | 22030 | Cycle 1(1): loaded http://localhost:49477/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49477/tests/dromaeo/cssquery-mootools.html) 18:51:32 INFO - PROCESS | 22030 | RSS: Main: 219398144 18:51:32 INFO - PROCESS | 22030 | 18:54:34 INFO - PROCESS | 22030 | Cycle 1(1): loaded http://localhost:49477/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49477/tests/dromaeo/cssquery-prototype.html) 18:54:35 INFO - PROCESS | 22030 | RSS: Main: 276746240 18:54:35 INFO - PROCESS | 22030 | 18:57:38 INFO - PROCESS | 22030 | Cycle 1(1): loaded http://localhost:49477/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49477/tests/dromaeo/cssquery-yui.html) 18:57:38 INFO - PROCESS | 22030 | RSS: Main: 217014272 18:57:38 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | Cycle 1(1): loaded http://localhost:49477/tests/dromaeo/cssquery-yui.html (next: http://localhost:49477/tests/dromaeo/cssquery-dojo.html) 19:00:41 INFO - PROCESS | 22030 | RSS: Main: 308789248 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | __start_tp_report 19:00:41 INFO - PROCESS | 22030 | _x_x_mozilla_page_load 19:00:41 INFO - PROCESS | 22030 | _x_x_mozilla_page_load_details 19:00:41 INFO - PROCESS | 22030 | |i|pagename|runs| 19:00:41 INFO - PROCESS | 22030 | |0;dojo.html;2950.1739512936374;2972.2123637236127;3005.428968495987;3029.8333591652477;3045.010371629221;5138.48615138486;5186.792528298873;5203.895922081556;5277.973610131949;5344.786208551658;5247.763850626721;5257;5281.445448227938;5283.973580132104;5368.275282836816;4759;4763.333133361332;4765.880852978686;4816.4461086974925;4904.975475122619;4903.068417000758;4926.989967056763;4941.678790878582;4943.728094954776;4979.128652485819;4668.136394766945;4697.365855609483;4862.878428039292;4934;4977.776000079987;4732.266498692674;4748.905021899543;4757.833475828329;4757.857264282077;4778.283257511366;4693.577578017995;4705.505921878188;4705.835295764632;4795.448523419782;4832.202686556714;5282.603804714623;5292.841214763564;5294.92057619136;5305.151175811851;5351;5421.83734487966;5473.370562385337;5499.065158922953;5553.111502159675;5556.527695145917;5615.438456154393;5616.438356164392;5621.409751976024;5631.493165615114;5634.239377684042;5232.372115346183;5232.581393488512;5235.895282094375;5237.764300606444;5288.286081378965;5384.78460861569;5535.391106978229;5599.888002239933;5688.488036076773;5739.397363276879;5544.528715059185;5545.473180047969;5546.362168350611;5548.0845660466375;5611.186377975171;5580.860478487989;5622.662640241599;5625.071863142617;5625.521830644441;5638.859028524238;5420.4850539198715;5450.345958485007;5478.287822583039;5481.451854814487;5585.273914391103;5484.643498172606;5522.6134170608475;5525.226468294442;5579.804706835242;5591.189277554732;4340;4341.9131617367475;4345.087531618396;4348.217320882209;4389.978050109729;3826.311263972512;3846.0961674006585;3891.902702432405;3896.08442016126;3917.8236979335998;5328.17413300939;5344.358676958712;5354.303940487712;5403.648762830482;5419.726364304385;5625;5629.43705629442;5634.352049514277;5634.830955071355;5637.098064309691;5178.508041736029;5264.789408423697;5359.946400536022;5359.946400536022;5426.945730542644;4791.305260737174;4812.470628230892;4813.807447702123;4833.782479788418;4893.045856058035;5748.310202775694;5749.310082789924;5752.597318187687;5753.568482363839;5796.362400135981;5448.700321482356;5467.5625949923115;5573.860653483695;5587.720613969366;5650.23721797552;5683.431656834284;5743.597948143757;5786.276715410574;5793.449622285876;5869.882602347844;5488.945110548843;5505.6696598204235;5510.972445137749;5513.614047016831;5514.972425138009;5592.692401917933;5610.102383618601;5610.5874473974945;5617.410171931889;5619.915701264566;5258.0009798138235;5313.787448502017;5447.346318441654;5456.399796022513;5485.670859748428;5479.589030822624;5505.642133261325;5514.724263786875;5537.529310008695;5557.972210138923;5524.889502209853;5532.640378375393;5535.981897398603;5539.418361072029;5540.33515978069;5306.310179676618;5354.143337066051;5419.729013549228;5429.647072940246;5568.693721845176;5377.704226267437;5378.569714422759;5380.73096345189;5421.349438067457;5427.755750991294 19:00:41 INFO - PROCESS | 22030 | |1;ext.html;550.9776433982815;584.4214227914365;597.7818096394815;600.9663378988139;618.177823494752;31218.219544511398;42289.78855105728;42981.35527967083;43039.78480107595;43131.56868431315;44488.55511444885;44889;44895.1020979581;44977.325340119925;44989.32516012262;8970.102989701043;9112.407693499901;9124.635014599407;9126.726198214064;9127.178553930145;8287.709930152447;8479.915200848005;10699.144068474543;10959.287646302964;10965.287256328313;10722.624708135218;10764;10778.083862871626;10779.353238805697;10779.892201077968;10948.835767463494;10968.670939871816;11000.24993751562;11042.95705294523;11054.336739795557;10895.128389728881;10937.56249750009;10962.780744385067;10966.232363734542;10967.067799237075;42940;42943.78528107371;43309.56690433119;43334.78332608317;43354.13291734179;3319.286353434023;3434.6565343465454;3448.534447849533;3455.7926524408617;3511.332846759133;31226.219344516343;31685;31880.681193188004;31938.680613193803;31983.360332793214;4401.581849724271;4420.4253447052;4434.490033646108;4435.955640443586;4495.5953964143055;351877.24061379785;352536;353871;354163;354725;34434.139146521535;57891.71054144702;58295;58306.7084664574;58323.70838145867;35443.645563544036;37915.24169516594;37923.43114853279;37995;38037.288322025575;13146.474141034443;13206.471741130246;13253.337333133497;13290.867091329157;13303.3348332583;11085.889141108648;11293.209475336811;12110.636680899586;12270.38648067593;12271.263724176579;8449.28181104613;8653.264472519906;8661.350398719995;8846.336524760669;8950.283977281802;3380.9323813524084;3412.2493051528622;3413.7269018478464;3416.257708568648;3441.9483707744403;957.2485598804905;963.4412041016194;1042.775803202315;1052.6368402900987;1083.6153165626247;31975;32174.517382239286;32406.35187296288;32508.83745581304;32658.510122348183;994.786120983992;1015.720676813882;1020.5713600287897;1050.9962985348973;1129.745807193375;16110.11121192743;17223.483295501155;17229.483115506555;17247.65504689899;17344.132793360535;542068.6621038552;544374;544476;545873;545979;58725.412745871996;59458.40541594615;59475.70262148661;59642.701786491656;59676.104858427156;1189.637160665988;1214.3806658603999;1279.2324605236784;1307.7384523095418;1320.7622627927065;28773.28066798347;32661.673383265865;33406.66593334036;33442.49836252507;33980.804614849665;50681.23978140257;51023.74488127536;51173;51182.488175117775;51193.23210151925;367182;369407;370257;370542;370581;28207.294817629725;28227.2943176414;28559.714402856538;28937.56593651055;29048.273793155342;47767.522324777696;47773.522264776904;48114.518854811;48386.51613483961;48973.75513122412;39339.46050960151;39833.40249896312;40430.72246995569;41593.79203103965;42570.787146064074;40164.79917600393;40382.39426408547;40761.79619101885;41748.165036699706;42113.999170078794;40211;41612.58387416087;41769.37345939874;42120.3681944765;42123.368149478396;34411.82794086014;36484.45273320849;36600.81699591485;36957.26085478329;37253.25493490169;27041.59437608398;27147.457050859266;27180.184594462196;27206.727932721213;27323.863380682968 19:00:41 INFO - PROCESS | 22030 | |2;jquery.html;366.1998533204949;378.16991703211426;383.2679581998382;390.51576045703325;409.4063607768736;14357.856421435796;18478.90760546197;18639.1612377443;19237.807621923777;20425.28511502098;15658.451954181606;19172.616547669073;19247.326343577977;19331;19479.902600487014;5604.971975140119;5869.911951320733;5872.442117998783;5876.9118463223085;5876.970615146918;5600.103983362663;5707.343655479611;5768.653880767168;5778.075507918734;5810.738516766734;5772.971135144318;5773.30720313563;5778.24882765242;5783.450572195635;5784.421557844224;5523.309586301712;5561.582881283879;5642.943570564283;5650.237217975562;5771.22088518051;4997.700137991732;5027.371578552681;5041.823536176253;5049.823256186016;5052.040112378636;23241.3027609172;23293.650595241084;23375;23557.646635300483;23564.29307120772;2252.842301038928;2318.5362927414417;2320.918767843117;2986.341307560213;3127.499600063979;13789.793153102712;13793.724125517534;13933.790993135111;13999.440022399194;14452.710945781131;1632.6326576520187;1673.4059408909784;1674.4093111544082;1681.6216351320977;1778.2442461953747;76879.23120768832;79051.6047419765;81172.18827811706;81277.59361203216;81418;9972.650957216463;10080.294379393372;10110.090091891765;10288.897111028944;10313.174946004301;13732.794008089886;13754.65613359674;13755.587332380044;13770.104943178661;13778.586642400744;7654.578998155043;7851.2148785121035;7881.527108373516;7916.563198410728;8127.390445716476;7494.5128566643;7521;7767.02912135983;7809.453338266267;7841.725539606086;6253.405926436982;6298.401651843067;6373.521985850987;6433.365148690975;6455.193100862392;1818.4635532517939;1823.1886810369524;1838.5771272607376;1905.6370101074567;1908.8186622270862;892.2282225874571;903.1510380242551;912.4069354919222;1008.9798204036025;1090.9781804363868;18661.720074198896;19786.70319945202;19915.800841991684;19934.50163745918;20052.498687532927;940.6519587752575;994.2791476179856;997.187292356727;1014.5130337438071;1023.3655133816932;10628.574857005788;11213.495392707347;11338.546458141582;11470;11496.597619083292;440348;441657.5845140111;459034;461780;464225;83227;84395.15604843995;84822.57588712017;84987.57506212553;85649.99999999875;992.1368409483651;1066.589363095214;1085.5983286184162;1087.923845330835;1092.6721983405105;16491.752623710414;16944.57638559046;17407.30370785205;17685.292588296837;17978.19098140613;37681.24637507289;38922.416163758135;39006;39183;40120.1975960485;51291.23063153981;51675.74162129165;52721;52881.7355913218;53263.20105198502;8181.140980197231;8188.099309076116;8250.504969701837;8350.749477515685;8468.66125354979;6478.4169424752;9595.808083838423;12211.26732396059;12448.502059917502;12516;9791.608335666493;10062.748431289276;10585.576576936835;10791.514381852678;11181.440927953732;10824.458777061272;10963.287386319864;11130.833037504282;11678.70803229926;12102.93948530287;10261.74345641365;10388.168946484415;10684.626038088629;10787.838182427427;10834.187435942493;8063.919360806552;8215.260626543639;8226.424150309422;8562.57187140653;8590;10396.376217426978;10568.365898046142;10641.414722190044;10654.680359589225;11091.50088246047 19:00:41 INFO - PROCESS | 22030 | |3;mootools.html;1355.2613825465132;1466.882649388049;1503.676709507456;1636.2555037457957;1776.0764402510695;7595.126560445544;8746.300295976322;9065.27477801776;9066.27469802415;9356.06439356064;9099.63601455941;9166.08339166083;9172.8624070639;9175.54122293886;9178.128077832594;2349.4361353275217;2375.3111597636666;2382.0471811275456;2383.7854593086618;2384.9284521464383;2297.632378819389;2300.2639155470256;2345.6950596422525;2347.084636991575;2349.060375849657;2307.792298693117;2320.1299512682745;2325.662778897059;2359.457324815285;2360.24472168906;2246.33733048751;2262.7171603549555;2273.8976746046383;2278.4076140203583;2312.90748370065;2286.931392058241;2297.276357947241;2297.414159389345;2302.504961433283;2307.573098976695;9031.458112513204;9079.092090790868;9134.406263592846;9141.177294043568;9146.634134634607;5378.924215156984;5481.616286859921;5484.396716361197;5490.286262785852;5554.333479982388;3410.437277849152;3419.606745224306;3475.2528206435486;3487.668671476206;3497.1431999160113;1119.9944000280027;1128.9322640641587;1129.9604513841975;1137.4653912661036;1149.408054851752;12877.420516076705;12939.48242070316;12940.547080852126;12974.610761677164;12980.480780768758;4426.336049592587;4613.653975951817;4661.091087237956;4781.187198176319;4781.617470602413;10018.849717254247;10039.548220330104;10041.397516148909;10053.497325133714;10054.396736195851;5208.270842082112;5215.269862219292;5216.895662086737;5216.921746173811;5218.66078704883;4304.849330273488;4438.201123797748;4826.831060912851;4873.415190177201;4930.704157750546;3804.144067584771;3828.617138286205;3898.8245528951265;3924.8822535323984;3946.1910308386714;1158.7276989907534;1179.4987130469517;1194.802857528498;1198.9460474278678;1205.727204220034;769.7113582406597;824.4970567953544;879.9792241000537;902.0393281155548;925.833349997007;5787;5840.795572155039;5914.817036592699;5951.613145145551;6231.626102433868;893.9865902011475;932.8460803967405;945.1163162443138;960.7790208252002;993.2153598657125;3897.5322961244265;4108.281050816096;4132.050067321622;4155.667546596265;4245.660347172219;11269.718257043476;11592.710182245342;11595.362255076052;11596.130290228099;11601.883981160248;11464.713382165346;11470.254433461798;11472.426378681033;11482;11548.884511154782;8483.109273526314;8486.830263394697;8492.787680307918;8544.31645468361;8574.099719529484;5763.193152958505;5763.221965034667;5890.204822348928;5945.464908158287;6035.758569657164;9632.711018669452;10422.843657345298;10471.3193642413;10501.737456563342;10557.049865512141;10089.445080520642;10253.28227024131;10337.017983291575;10403.115735162595;10412.427316497666;6992.3357281058215;7033.296670332926;7529.360004399688;8056.637451314587;8233.259006689426;8455.873161902453;8686.609102590272;8769.91230087691;8779.25376343018;8779.429337093068;7539.924600753922;7624.237576242332;7801.648925798466;7839.9216007839195;7928.603569821601;7578.393728501596;7724.691012359667;7766.378689704923;7771.92228077712;7984.321332686786;6515.511336649675;6605.174353205849;6626.403623673892;6627.138471998609;6632.104665870046;4705.270683044134;4748.857534273978;4758.524147585353;4845.370101886732;4939.629527785502;4855.854324370274;4963.801447942042;5049.2426136079885;5069.5944324454695;5091 19:00:41 INFO - PROCESS | 22030 | |4;prototype.html;316.21105342171285;320.94383482890487;330.1992667780632;334.0607771884211;334.74224846867895;4932.432770231425;5262.342207224097;5287.39194992576;5292.015494189679;5304.761285742136;5249.1601343785005;5265.156815319723;5274.868128296795;5288;5307.469253074691;4134.6485548728315;4174.499060112776;4178.143480586473;4182.769947652878;4192.203481338536;3910.7262491625456;4129.6076872697195;4170.998960249533;4176.1230141670285;4210.221109094828;4077.1845630873795;4091.856785012525;4093.672506199497;4095.5699651536606;4100.056986893016;4045.9797701011603;4088.9795551022203;4094.406311084891;4098.057446787256;4099.262132816092;3599.2981368633127;3616.674499295076;3617.0776452004834;3628.657396763188;3642.3079614873354;5077.543021128116;5132.769025393866;5137.537621614073;5140.820071297487;5189.636725429222;4642.721436713808;4647.95352046482;4648.651351148677;4660.557247061523;4661.207594708908;5438.592105592096;5443.727813609344;5457.617966742329;5461.808836690737;5510.8071217507595;3768.4158955361963;3897.7856217908;3913.5344732818426;3923.333033384303;3929.5284565851994;5412.783488660449;5555.638883472561;5659;5693.658380497183;5702.429757024264;3868.419737039467;3871.6128387161057;3872.1868407634147;3941.034446560611;4142.192272506893;4142.647874930604;4148.377743338523;4151.6886233532005;4151.813168407429;4185.895352616148;4629.96281636079;4697.577218050392;4707.964247865464;4730.432348118248;4746.691465054761;5490.533304669148;5492.752826122834;5515.420880807457;5532.612717109763;5546.972265138648;1420.8579142085916;1438.4030627289558;1439.755241608929;1440.3158499712554;1441.704450587627;892.5314210039651;923.8244733500748;946.186279799372;956.3018996132723;961.0437614573405;4650.070220858373;4677.812887484531;4720.362751028636;4733.5266473352385;4801.68789028712;4921.5078492151215;4961.3798275215595;4990.700557966534;4995.825146119869;5007.572841740087;4755.025219829927;4756.357891684647;4769.427668679711;4781.378420805343;5099.64302498829;2951.7343439090587;3005.9549106763416;3023.365093330381;3026.334206474572;3038.3771326877936;5897.469227769521;5899.734511946973;5922.851928701731;5998.820035398945;6002.459778619946;5579.9163012554845;5779.393163717833;5783.624064435799;5786.594938354359;5866.5893387462465;5882.735276912549;5889.263842019747;5889.705514724246;5890.705464726747;5926.555508336978;5398.514133727984;5632.014397480507;5644.294463192101;5730.484256416942;5752.568557358131;5570.164475328733;5578.916316255179;5580.637258578179;5604.215409842707;5611.793464405173;5624.775008999758;5679.290088738908;5816.185733997159;5885.352611212848;5895.380984996514;4081.5510293868247;4088.816003279919;4089.775062371479;4090.877273681794;4113;5669.376368599534;5684.914726279027;5689.089745640677;5716.342620598684;5734.569907256889;5330.614040349459;5546.473085056913;5551.25058117149;5579.609427340048;5614.550835933197;5534.782347883459;5548.361938377138;5556.722163891708;5572.60991730575;5573.9343361150395;5386.5152136307925;5583.297094386251;5589.916151257816;5625.887482250414;5628.409017053151;5546.473085056913;5575.107982722745;5585.748641311069;5616.522595579258;5617.297837770278;5468.261784659179;5475.945240547543;5522.779088836402;5537.335519737658;5543.1685247213245 19:00:41 INFO - PROCESS | 22030 | |5;yui.html;687.5496549759906;714.1430283659608;760.228368206271;774.9186335434781;782.6438970268529;14422.927885360572;15554.922225388886;15594.688106237869;15685.764713529292;15692.372305107796;13082.345882705851;13104.213747175176;13156.934215328934;13164.736705265888;13187.142835715671;659.7921654678761;678.131991051455;749.4641331448014;784.8822676598498;805.2149154574302;782.0498094814799;783.3185128937832;789.2226157235148;790.1032328307355;790.6876783670447;831.8294749576322;837.9664813407456;839.03091928822;839.9328053755715;840.1850205300848;684.2165720250335;702.4696354252558;829.9419040667156;831.4844796226348;831.5925196653657;599.691159053088;607.5838050935124;608.6713174885557;616.202018386191;622.6419808610067;14217.360218790076;14224;14245.572632821031;14246.430142794276;14278.500252491112;12579.937100314532;13053.34733263333;13481.123726957716;13577.253251071186;13857.514986975408;11908.58319958806;12017.278963262232;12062.939685301604;12096.879031209752;12385.876141238652;4145.958540414617;4215.915681686349;4223.345381465878;4232.576742325774;4283.828646854122;62485.37514624841;64730.90863642045;64778;74068;74271;10689.465526723632;10785.191110666574;10829.945850270698;11022.283551569122;11026.889731102747;20988.37034888956;21051.68422473664;21065.157393704387;21066.262680806405;21070.47323816917;8182.140875208018;8204.384671149688;8288.46125001885;8464.915350846413;8587.05542390349;4194.2869712149;4274.081072569413;4303.074838909587;4358.455193100863;4470;3674.0998455378603;3784.5836957934607;3813.828377723009;3824.4263360496147;3835.060410199463;640.7276907314373;652.3248437866785;661.7584581627767;662.483263054818;676.6616691654173;655.9639219842859;733.1078893764156;741.6440108747723;745.4297462441236;748.1396394146775;8808.823823523493;8946.463212207289;9247.861282080774;9376.249900007982;9463.148316651534;595.3243069116497;712.28059659743;741.458735123363;746.8431629357896;750.673968195651;4273.87178384649;4325.3728209409455;4652.325412815123;4654.953450465519;4656.534346565384;115714;120892;121679;123122;123155;102111;108200;109931;112198.43900780607;113900.43049784697;72973;74107.25892741111;90995.54502227447;94525.5273723627;96183;9257;9419.199368053793;9469.195118415011;9471.242300616072;9718.465484398426;47533.76233118951;49150;49945;51183.74408127936;53926.460735392146;73179.63410182916;74142.62928685537;77106;78011.60994194992;79782.2021779798;12271.447784849524;12340.938295308466;12395.256284622952;12573.789258871913;13243.139195952232;12960.870391295966;13374.465021399035;13735.656608584864;13876.722465550834;14202.502912398017;11121.277116987694;11227.157963152633;11321.490532926204;11751.471183796579;11757.294562326288;12816;13002.414891330101;13248.337583120612;13312;13585.52450664222;11841.23032002917;12152.817707734568;12181.939090304491;12418.75162496763;13091.214527128403;10776.407297598707;10899.782004360028;11027.44862756875;11557.42212889369;11660;10125.696229113139;10544.630937917136;10872.075873550837;10952.397618131079;11069.225154239126 19:00:41 INFO - PROCESS | 22030 | __end_tp_report 19:00:41 INFO - PROCESS | 22030 | __start_cc_report 19:00:41 INFO - PROCESS | 22030 | _x_x_mozilla_cycle_collect,1175 19:00:41 INFO - PROCESS | 22030 | __end_cc_report 19:00:41 INFO - PROCESS | 22030 | __startTimestamp1475200841616__endTimestamp 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | ------- Summary: start ------- 19:00:41 INFO - PROCESS | 22030 | Number of tests: 6 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | [#0] dojo.html Cycles:165 Average:5208.79 Median:5419.73 stddev:574.97 (10.6%) stddev-sans-first:548.76 19:00:41 INFO - PROCESS | 22030 | Values: 2950.2 2972.2 3005.4 3029.8 3045.0 5138.5 5186.8 5203.9 5278.0 5344.8 5247.8 5257.0 5281.4 5284.0 5368.3 4759.0 4763.3 4765.9 4816.4 4905.0 4903.1 4927.0 4941.7 4943.7 4979.1 4668.1 4697.4 4862.9 4934.0 4977.8 4732.3 4748.9 4757.8 4757.9 4778.3 4693.6 4705.5 4705.8 4795.4 4832.2 5282.6 5292.8 5294.9 5305.2 5351.0 5421.8 5473.4 5499.1 5553.1 5556.5 5615.4 5616.4 5621.4 5631.5 5634.2 5232.4 5232.6 5235.9 5237.8 5288.3 5384.8 5535.4 5599.9 5688.5 5739.4 5544.5 5545.5 5546.4 5548.1 5611.2 5580.9 5622.7 5625.1 5625.5 5638.9 5420.5 5450.3 5478.3 5481.5 5585.3 5484.6 5522.6 5525.2 5579.8 5591.2 4340.0 4341.9 4345.1 4348.2 4390.0 3826.3 3846.1 3891.9 3896.1 3917.8 5328.2 5344.4 5354.3 5403.6 5419.7 5625.0 5629.4 5634.4 5634.8 5637.1 5178.5 5264.8 5359.9 5359.9 5426.9 4791.3 4812.5 4813.8 4833.8 4893.0 5748.3 5749.3 5752.6 5753.6 5796.4 5448.7 5467.6 5573.9 5587.7 5650.2 5683.4 5743.6 5786.3 5793.4 5869.9 5488.9 5505.7 5511.0 5513.6 5515.0 5592.7 5610.1 5610.6 5617.4 5619.9 5258.0 5313.8 5447.3 5456.4 5485.7 5479.6 5505.6 5514.7 5537.5 5558.0 5524.9 5532.6 5536.0 5539.4 5540.3 5306.3 5354.1 5419.7 5429.6 5568.7 5377.7 5378.6 5380.7 5421.3 5427.8 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | [#1] ext.html Cycles:180 Average:57649.66 Median:201891.41 stddev:114469.25 (56.7%) stddev-sans-first:114710.09 19:00:41 INFO - PROCESS | 22030 | Values: 551.0 584.4 597.8 601.0 618.2 31218.2 42289.8 42981.4 43039.8 43131.6 44488.6 44889.0 44895.1 44977.3 44989.3 8970.1 9112.4 9124.6 9126.7 9127.2 8287.7 8479.9 10699.1 10959.3 10965.3 10722.6 10764.0 10778.1 10779.4 10779.9 10948.8 10968.7 11000.2 11043.0 11054.3 10895.1 10937.6 10962.8 10966.2 10967.1 42940.0 42943.8 43309.6 43334.8 43354.1 3319.3 3434.7 3448.5 3455.8 3511.3 31226.2 31685.0 31880.7 31938.7 31983.4 4401.6 4420.4 4434.5 4436.0 4495.6 351877.2 352536.0 353871.0 354163.0 354725.0 34434.1 57891.7 58295.0 58306.7 58323.7 35443.6 37915.2 37923.4 37995.0 38037.3 13146.5 13206.5 13253.3 13290.9 13303.3 11085.9 11293.2 12110.6 12270.4 12271.3 8449.3 8653.3 8661.4 8846.3 8950.3 3380.9 3412.2 3413.7 3416.3 3441.9 957.2 963.4 1042.8 1052.6 1083.6 31975.0 32174.5 32406.4 32508.8 32658.5 994.8 1015.7 1020.6 1051.0 1129.7 16110.1 17223.5 17229.5 17247.7 17344.1 542068.7 544374.0 544476.0 545873.0 545979.0 58725.4 59458.4 59475.7 59642.7 59676.1 1189.6 1214.4 1279.2 1307.7 1320.8 28773.3 32661.7 33406.7 33442.5 33980.8 50681.2 51023.7 51173.0 51182.5 51193.2 367182.0 369407.0 370257.0 370542.0 370581.0 28207.3 28227.3 28559.7 28937.6 29048.3 47767.5 47773.5 48114.5 48386.5 48973.8 39339.5 39833.4 40430.7 41593.8 42570.8 40164.8 40382.4 40761.8 41748.2 42114.0 40211.0 41612.6 41769.4 42120.4 42123.4 34411.8 36484.5 36600.8 36957.3 37253.3 27041.6 27147.5 27180.2 27206.7 27323.9 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | [#2] jquery.html Cycles:180 Average:27374.77 Median:19029.71 stddev:74765.63 (392.9%) stddev-sans-first:74947.86 19:00:41 INFO - PROCESS | 22030 | Values: 366.2 378.2 383.3 390.5 409.4 14357.9 18478.9 18639.2 19237.8 20425.3 15658.5 19172.6 19247.3 19331.0 19479.9 5605.0 5869.9 5872.4 5876.9 5877.0 5600.1 5707.3 5768.7 5778.1 5810.7 5773.0 5773.3 5778.2 5783.5 5784.4 5523.3 5561.6 5642.9 5650.2 5771.2 4997.7 5027.4 5041.8 5049.8 5052.0 23241.3 23293.7 23375.0 23557.6 23564.3 2252.8 2318.5 2320.9 2986.3 3127.5 13789.8 13793.7 13933.8 13999.4 14452.7 1632.6 1673.4 1674.4 1681.6 1778.2 76879.2 79051.6 81172.2 81277.6 81418.0 9972.7 10080.3 10110.1 10288.9 10313.2 13732.8 13754.7 13755.6 13770.1 13778.6 7654.6 7851.2 7881.5 7916.6 8127.4 7494.5 7521.0 7767.0 7809.5 7841.7 6253.4 6298.4 6373.5 6433.4 6455.2 1818.5 1823.2 1838.6 1905.6 1908.8 892.2 903.2 912.4 1009.0 1091.0 18661.7 19786.7 19915.8 19934.5 20052.5 940.7 994.3 997.2 1014.5 1023.4 10628.6 11213.5 11338.5 11470.0 11496.6 440348.0 441657.6 459034.0 461780.0 464225.0 83227.0 84395.2 84822.6 84987.6 85650.0 992.1 1066.6 1085.6 1087.9 1092.7 16491.8 16944.6 17407.3 17685.3 17978.2 37681.2 38922.4 39006.0 39183.0 40120.2 51291.2 51675.7 52721.0 52881.7 53263.2 8181.1 8188.1 8250.5 8350.7 8468.7 6478.4 9595.8 12211.3 12448.5 12516.0 9791.6 10062.7 10585.6 10791.5 11181.4 10824.5 10963.3 11130.8 11678.7 12102.9 10261.7 10388.2 10684.6 10787.8 10834.2 8063.9 8215.3 8226.4 8562.6 8590.0 10396.4 10568.4 10641.4 10654.7 11091.5 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | [#3] mootools.html Cycles:180 Average:5859.02 Median:4753.69 stddev:3426.18 (72.1%) stddev-sans-first:3419.08 19:00:41 INFO - PROCESS | 22030 | Values: 1355.3 1466.9 1503.7 1636.3 1776.1 7595.1 8746.3 9065.3 9066.3 9356.1 9099.6 9166.1 9172.9 9175.5 9178.1 2349.4 2375.3 2382.0 2383.8 2384.9 2297.6 2300.3 2345.7 2347.1 2349.1 2307.8 2320.1 2325.7 2359.5 2360.2 2246.3 2262.7 2273.9 2278.4 2312.9 2286.9 2297.3 2297.4 2302.5 2307.6 9031.5 9079.1 9134.4 9141.2 9146.6 5378.9 5481.6 5484.4 5490.3 5554.3 3410.4 3419.6 3475.3 3487.7 3497.1 1120.0 1128.9 1130.0 1137.5 1149.4 12877.4 12939.5 12940.5 12974.6 12980.5 4426.3 4613.7 4661.1 4781.2 4781.6 10018.8 10039.5 10041.4 10053.5 10054.4 5208.3 5215.3 5216.9 5216.9 5218.7 4304.8 4438.2 4826.8 4873.4 4930.7 3804.1 3828.6 3898.8 3924.9 3946.2 1158.7 1179.5 1194.8 1198.9 1205.7 769.7 824.5 880.0 902.0 925.8 5787.0 5840.8 5914.8 5951.6 6231.6 894.0 932.8 945.1 960.8 993.2 3897.5 4108.3 4132.1 4155.7 4245.7 11269.7 11592.7 11595.4 11596.1 11601.9 11464.7 11470.3 11472.4 11482.0 11548.9 8483.1 8486.8 8492.8 8544.3 8574.1 5763.2 5763.2 5890.2 5945.5 6035.8 9632.7 10422.8 10471.3 10501.7 10557.0 10089.4 10253.3 10337.0 10403.1 10412.4 6992.3 7033.3 7529.4 8056.6 8233.3 8455.9 8686.6 8769.9 8779.3 8779.4 7539.9 7624.2 7801.6 7839.9 7928.6 7578.4 7724.7 7766.4 7771.9 7984.3 6515.5 6605.2 6626.4 6627.1 6632.1 4705.3 4748.9 4758.5 4845.4 4939.6 4855.9 4963.8 5049.2 5069.6 5091.0 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | [#4] prototype.html Cycles:180 Average:4611.24 Median:5219.40 stddev:1353.39 (25.9%) stddev-sans-first:1318.23 19:00:41 INFO - PROCESS | 22030 | Values: 316.2 320.9 330.2 334.1 334.7 4932.4 5262.3 5287.4 5292.0 5304.8 5249.2 5265.2 5274.9 5288.0 5307.5 4134.6 4174.5 4178.1 4182.8 4192.2 3910.7 4129.6 4171.0 4176.1 4210.2 4077.2 4091.9 4093.7 4095.6 4100.1 4046.0 4089.0 4094.4 4098.1 4099.3 3599.3 3616.7 3617.1 3628.7 3642.3 5077.5 5132.8 5137.5 5140.8 5189.6 4642.7 4648.0 4648.7 4660.6 4661.2 5438.6 5443.7 5457.6 5461.8 5510.8 3768.4 3897.8 3913.5 3923.3 3929.5 5412.8 5555.6 5659.0 5693.7 5702.4 3868.4 3871.6 3872.2 3941.0 4142.2 4142.6 4148.4 4151.7 4151.8 4185.9 4630.0 4697.6 4708.0 4730.4 4746.7 5490.5 5492.8 5515.4 5532.6 5547.0 1420.9 1438.4 1439.8 1440.3 1441.7 892.5 923.8 946.2 956.3 961.0 4650.1 4677.8 4720.4 4733.5 4801.7 4921.5 4961.4 4990.7 4995.8 5007.6 4755.0 4756.4 4769.4 4781.4 5099.6 2951.7 3006.0 3023.4 3026.3 3038.4 5897.5 5899.7 5922.9 5998.8 6002.5 5579.9 5779.4 5783.6 5786.6 5866.6 5882.7 5889.3 5889.7 5890.7 5926.6 5398.5 5632.0 5644.3 5730.5 5752.6 5570.2 5578.9 5580.6 5604.2 5611.8 5624.8 5679.3 5816.2 5885.4 5895.4 4081.6 4088.8 4089.8 4090.9 4113.0 5669.4 5684.9 5689.1 5716.3 5734.6 5330.6 5546.5 5551.3 5579.6 5614.6 5534.8 5548.4 5556.7 5572.6 5573.9 5386.5 5583.3 5589.9 5625.9 5628.4 5546.5 5575.1 5585.7 5616.5 5617.3 5468.3 5475.9 5522.8 5537.3 5543.2 19:00:41 INFO - PROCESS | 22030 | 19:00:41 INFO - PROCESS | 22030 | [#5] yui.html Cycles:180 Average:20732.04 Median:4273.98 stddev:31031.12 (726.0%) stddev-sans-first:31081.67 19:00:41 INFO - PROCESS | 22030 | Values: 687.5 714.1 760.2 774.9 782.6 14422.9 15554.9 15594.7 15685.8 15692.4 13082.3 13104.2 13156.9 13164.7 13187.1 659.8 678.1 749.5 784.9 805.2 782.0 783.3 789.2 790.1 790.7 831.8 838.0 839.0 839.9 840.2 684.2 702.5 829.9 831.5 831.6 599.7 607.6 608.7 616.2 622.6 14217.4 14224.0 14245.6 14246.4 14278.5 12579.9 13053.3 13481.1 13577.3 13857.5 11908.6 12017.3 12062.9 12096.9 12385.9 4146.0 4215.9 4223.3 4232.6 4283.8 62485.4 64730.9 64778.0 74068.0 74271.0 10689.5 10785.2 10829.9 11022.3 11026.9 20988.4 21051.7 21065.2 21066.3 21070.5 8182.1 8204.4 8288.5 8464.9 8587.1 4194.3 4274.1 4303.1 4358.5 4470.0 3674.1 3784.6 3813.8 3824.4 3835.1 640.7 652.3 661.8 662.5 676.7 656.0 733.1 741.6 745.4 748.1 8808.8 8946.5 9247.9 9376.2 9463.1 595.3 712.3 741.5 746.8 750.7 4273.9 4325.4 4652.3 4655.0 4656.5 115714.0 120892.0 121679.0 123122.0 123155.0 102111.0 108200.0 109931.0 112198.4 113900.4 72973.0 74107.3 90995.5 94525.5 96183.0 9257.0 9419.2 9469.2 9471.2 9718.5 47533.8 49150.0 49945.0 51183.7 53926.5 73179.6 74142.6 77106.0 78011.6 79782.2 12271.4 12340.9 12395.3 12573.8 13243.1 12960.9 13374.5 13735.7 13876.7 14202.5 11121.3 11227.2 11321.5 11751.5 11757.3 12816.0 13002.4 13248.3 13312.0 13585.5 11841.2 12152.8 12181.9 12418.8 13091.2 10776.4 10899.8 11027.4 11557.4 11660.0 10125.7 10544.6 10872.1 10952.4 11069.2 19:00:41 INFO - PROCESS | 22030 | -------- Summary: end -------- 19:00:41 INFO - PROCESS | 22030 | 19:00:42 INFO - TEST-INFO | 22030: exit 0 19:00:42 INFO - TEST-OK | dromaeo_css | took 1115468ms 19:00:42 INFO - TEST-START | kraken 19:00:42 INFO - operating with platform_type : linux_ 19:00:42 INFO - Initialising browser for kraken test... 19:00:42 INFO - TEST-INFO | started process 22335 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKdKJBr/profile http://localhost:49477/getInfo.html) 19:00:46 INFO - PROCESS | 22335 | __metrics Screen width/height:1600/1200 19:00:46 INFO - PROCESS | 22335 | colorDepth:24 19:00:46 INFO - PROCESS | 22335 | Browser inner width/height: 1024/697 19:00:46 INFO - PROCESS | 22335 | __metrics 19:00:47 INFO - PROCESS | 22335 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:00:47 INFO - PROCESS | 22335 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:00:48 INFO - PROCESS | 22335 | console.error: PushService: 19:00:48 INFO - PROCESS | 22335 | stateChangeProcessEnqueue: Error transitioning state 19:00:48 INFO - PROCESS | 22335 | UnknownError 19:00:48 INFO - TEST-INFO | 22335: exit 0 19:00:48 INFO - Browser initialized. 19:00:48 INFO - Running cycle 1/1 for kraken test... 19:00:48 INFO - TEST-INFO | started process 22422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKdKJBr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:00 INFO - PROCESS | 22422 | RSS: Main: 173740032 19:01:00 INFO - PROCESS | 22422 | 19:01:03 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49477/tests/kraken/driver.html?testName=audio-beat-detection) 19:01:04 INFO - PROCESS | 22422 | RSS: Main: 232693760 19:01:04 INFO - PROCESS | 22422 | 19:01:06 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49477/tests/kraken/driver.html?testName=audio-dft) 19:01:07 INFO - PROCESS | 22422 | RSS: Main: 217649152 19:01:07 INFO - PROCESS | 22422 | 19:01:10 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49477/tests/kraken/driver.html?testName=audio-fft) 19:01:11 INFO - PROCESS | 22422 | RSS: Main: 299683840 19:01:11 INFO - PROCESS | 22422 | 19:01:13 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49477/tests/kraken/driver.html?testName=audio-oscillator) 19:01:13 INFO - PROCESS | 22422 | RSS: Main: 207990784 19:01:13 INFO - PROCESS | 22422 | 19:01:16 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49477/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:01:16 INFO - PROCESS | 22422 | RSS: Main: 213041152 19:01:16 INFO - PROCESS | 22422 | 19:01:19 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49477/tests/kraken/driver.html?testName=imaging-darkroom) 19:01:20 INFO - PROCESS | 22422 | RSS: Main: 249470976 19:01:20 INFO - PROCESS | 22422 | 19:01:23 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49477/tests/kraken/driver.html?testName=imaging-desaturate) 19:01:24 INFO - PROCESS | 22422 | RSS: Main: 248512512 19:01:24 INFO - PROCESS | 22422 | 19:01:26 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49477/tests/kraken/driver.html?testName=json-parse-financial) 19:01:27 INFO - PROCESS | 22422 | RSS: Main: 249090048 19:01:27 INFO - PROCESS | 22422 | 19:01:29 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49477/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:01:29 INFO - PROCESS | 22422 | RSS: Main: 210874368 19:01:29 INFO - PROCESS | 22422 | 19:01:31 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:01:32 INFO - PROCESS | 22422 | RSS: Main: 218615808 19:01:32 INFO - PROCESS | 22422 | 19:01:34 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:01:34 INFO - PROCESS | 22422 | RSS: Main: 222810112 19:01:34 INFO - PROCESS | 22422 | 19:01:37 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:01:37 INFO - PROCESS | 22422 | RSS: Main: 223539200 19:01:37 INFO - PROCESS | 22422 | 19:01:40 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:01:41 INFO - PROCESS | 22422 | RSS: Main: 221655040 19:01:41 INFO - PROCESS | 22422 | 19:01:42 INFO - PROCESS | 22422 | Cycle 1(1): loaded http://localhost:49477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49477/tests/kraken/driver.html?testName=ai-astar) 19:01:43 INFO - PROCESS | 22422 | RSS: Main: 272670720 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | __start_tp_report 19:01:43 INFO - PROCESS | 22422 | _x_x_mozilla_page_load 19:01:43 INFO - PROCESS | 22422 | _x_x_mozilla_page_load_details 19:01:43 INFO - PROCESS | 22422 | |i|pagename|runs| 19:01:43 INFO - PROCESS | 22422 | |0;ai-astar;110;112;112;114;113;115;111;112;113;112 19:01:43 INFO - PROCESS | 22422 | |1;audio-beat-detection;148;119;116;156;121;121;122;116;144;118 19:01:43 INFO - PROCESS | 22422 | |2;audio-dft;160;160;160;160;160;161;160;161;162;161 19:01:43 INFO - PROCESS | 22422 | |3;audio-fft;77;74;76;108;76;76;76;76;109;75 19:01:43 INFO - PROCESS | 22422 | |4;audio-oscillator;83;82;107;83;83;83;100;82;82;113 19:01:43 INFO - PROCESS | 22422 | |5;imaging-gaussian-blur;118;117;117;118;117;118;118;118;117;118 19:01:43 INFO - PROCESS | 22422 | |6;imaging-darkroom;145;146;145;146;145;147;145;145;146;146 19:01:43 INFO - PROCESS | 22422 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;95 19:01:43 INFO - PROCESS | 22422 | |8;json-parse-financial;52;51;51;51;52;52;53;52;52;52 19:01:43 INFO - PROCESS | 22422 | |9;json-stringify-tinderbox;59;60;60;60;60;60;60;61;59;59 19:01:43 INFO - PROCESS | 22422 | |10;stanford-crypto-aes;85;78;79;84;79;79;84;78;78;83 19:01:43 INFO - PROCESS | 22422 | |11;stanford-crypto-ccm;123;122;124;124;132;133;124;124;156;133 19:01:43 INFO - PROCESS | 22422 | |12;stanford-crypto-pbkdf2;168;166;172;169;163;163;165;162;166;163 19:01:43 INFO - PROCESS | 22422 | |13;stanford-crypto-sha256-iterative;66;64;65;68;64;65;64;65;65;69 19:01:43 INFO - PROCESS | 22422 | __end_tp_report 19:01:43 INFO - PROCESS | 22422 | __start_cc_report 19:01:43 INFO - PROCESS | 22422 | _x_x_mozilla_cycle_collect,1633 19:01:43 INFO - PROCESS | 22422 | __end_cc_report 19:01:43 INFO - PROCESS | 22422 | __startTimestamp1475200903209__endTimestamp 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | ------- Summary: start ------- 19:01:43 INFO - PROCESS | 22422 | Number of tests: 14 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:1.43 (1.3%) stddev-sans-first:1.22 19:01:43 INFO - PROCESS | 22422 | Values: 110.0 112.0 112.0 114.0 113.0 115.0 111.0 112.0 113.0 112.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:121.50 stddev:15.07 (12.4%) stddev-sans-first:14.15 19:01:43 INFO - PROCESS | 22422 | Values: 148.0 119.0 116.0 156.0 121.0 121.0 122.0 116.0 144.0 118.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 19:01:43 INFO - PROCESS | 22422 | Values: 160.0 160.0 160.0 160.0 160.0 161.0 160.0 161.0 162.0 161.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#3] audio-fft Cycles:10 Average:82.30 Median:76.00 stddev:13.83 (18.2%) stddev-sans-first:14.54 19:01:43 INFO - PROCESS | 22422 | Values: 77.0 74.0 76.0 108.0 76.0 76.0 76.0 76.0 109.0 75.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:82.50 stddev:12.04 (14.6%) stddev-sans-first:12.52 19:01:43 INFO - PROCESS | 22422 | Values: 83.0 82.0 107.0 83.0 83.0 83.0 100.0 82.0 82.0 113.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:01:43 INFO - PROCESS | 22422 | Values: 118.0 117.0 117.0 118.0 117.0 118.0 118.0 118.0 117.0 118.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 19:01:43 INFO - PROCESS | 22422 | Values: 145.0 146.0 145.0 146.0 145.0 147.0 145.0 145.0 146.0 146.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:01:43 INFO - PROCESS | 22422 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 19:01:43 INFO - PROCESS | 22422 | Values: 52.0 51.0 51.0 51.0 52.0 52.0 53.0 52.0 52.0 52.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 19:01:43 INFO - PROCESS | 22422 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 59.0 59.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.00 stddev:2.91 (3.6%) stddev-sans-first:2.64 19:01:43 INFO - PROCESS | 22422 | Values: 85.0 78.0 79.0 84.0 79.0 79.0 84.0 78.0 78.0 83.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#11] stanford-crypto-ccm Cycles:10 Average:129.50 Median:128.00 stddev:10.29 (8.0%) stddev-sans-first:10.64 19:01:43 INFO - PROCESS | 22422 | Values: 123.0 122.0 124.0 124.0 132.0 133.0 124.0 124.0 156.0 133.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:166.00 stddev:3.20 (1.9%) stddev-sans-first:3.28 19:01:43 INFO - PROCESS | 22422 | Values: 168.0 166.0 172.0 169.0 163.0 163.0 165.0 162.0 166.0 163.0 19:01:43 INFO - PROCESS | 22422 | 19:01:43 INFO - PROCESS | 22422 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.00 stddev:1.72 (2.6%) stddev-sans-first:1.81 19:01:43 INFO - PROCESS | 22422 | Values: 66.0 64.0 65.0 68.0 64.0 65.0 64.0 65.0 65.0 69.0 19:01:43 INFO - PROCESS | 22422 | -------- Summary: end -------- 19:01:43 INFO - PROCESS | 22422 | 19:01:44 INFO - TEST-INFO | 22422: exit 0 19:01:44 INFO - TEST-OK | kraken | took 61834ms 19:01:44 INFO - SUITE-END | took 1177s 19:01:44 INFO - Completed test suite (00:19:37) 19:01:44 INFO - javascript benchmark 19:01:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2950.1739512936374, 2972.2123637236127, 3005.428968495987, 3029.8333591652477, 3045.010371629221, 5138.48615138486, 5186.792528298873, 5203.895922081556, 5277.973610131949, 5344.786208551658, 5247.763850626721, 5257.0, 5281.445448227938, 5283.973580132104, 5368.275282836816, 4759.0, 4763.333133361332, 4765.880852978686, 4816.4461086974925, 4904.975475122619, 4903.068417000758, 4926.989967056763, 4941.678790878582, 4943.728094954776, 4979.128652485819, 4668.136394766945, 4697.365855609483, 4862.878428039292, 4934.0, 4977.776000079987, 4732.266498692674, 4748.905021899543, 4757.833475828329, 4757.857264282077, 4778.283257511366, 4693.577578017995, 4705.505921878188, 4705.835295764632, 4795.448523419782, 4832.202686556714, 5282.603804714623, 5292.841214763564, 5294.92057619136, 5305.151175811851, 5351.0, 5421.83734487966, 5473.370562385337, 5499.065158922953, 5553.111502159675, 5556.527695145917, 5615.438456154393, 5616.438356164392, 5621.409751976024, 5631.493165615114, 5634.239377684042, 5232.372115346183, 5232.581393488512, 5235.895282094375, 5237.764300606444, 5288.286081378965, 5384.78460861569, 5535.391106978229, 5599.888002239933, 5688.488036076773, 5739.397363276879, 5544.528715059185, 5545.473180047969, 5546.362168350611, 5548.0845660466375, 5611.186377975171, 5580.860478487989, 5622.662640241599, 5625.071863142617, 5625.521830644441, 5638.859028524238, 5420.4850539198715, 5450.345958485007, 5478.287822583039, 5481.451854814487, 5585.273914391103, 5484.643498172606, 5522.6134170608475, 5525.226468294442, 5579.804706835242, 5591.189277554732, 4340.0, 4341.9131617367475, 4345.087531618396, 4348.217320882209, 4389.978050109729, 3826.311263972512, 3846.0961674006585, 3891.902702432405, 3896.08442016126, 3917.8236979335998, 5328.17413300939, 5344.358676958712, 5354.303940487712, 5403.648762830482, 5419.726364304385, 5625.0, 5629.43705629442, 5634.352049514277, 5634.830955071355, 5637.098064309691, 5178.508041736029, 5264.789408423697, 5359.946400536022, 5359.946400536022, 5426.945730542644, 4791.305260737174, 4812.470628230892, 4813.807447702123, 4833.782479788418, 4893.045856058035, 5748.310202775694, 5749.310082789924, 5752.597318187687, 5753.568482363839, 5796.362400135981, 5448.700321482356, 5467.5625949923115, 5573.860653483695, 5587.720613969366, 5650.23721797552, 5683.431656834284, 5743.597948143757, 5786.276715410574, 5793.449622285876, 5869.882602347844, 5488.945110548843, 5505.6696598204235, 5510.972445137749, 5513.614047016831, 5514.972425138009, 5592.692401917933, 5610.102383618601, 5610.5874473974945, 5617.410171931889, 5619.915701264566, 5258.0009798138235, 5313.787448502017, 5447.346318441654, 5456.399796022513, 5485.670859748428, 5479.589030822624, 5505.642133261325, 5514.724263786875, 5537.529310008695, 5557.972210138923, 5524.889502209853, 5532.640378375393, 5535.981897398603, 5539.418361072029, 5540.33515978069, 5306.310179676618, 5354.143337066051, 5419.729013549228, 5429.647072940246, 5568.693721845176, 5377.704226267437, 5378.569714422759, 5380.73096345189, 5421.349438067457, 5427.755750991294], "value": 5170.540432800289, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.9776433982815, 584.4214227914365, 597.7818096394815, 600.9663378988139, 618.177823494752, 31218.219544511398, 42289.78855105728, 42981.35527967083, 43039.78480107595, 43131.56868431315, 44488.55511444885, 44889.0, 44895.1020979581, 44977.325340119925, 44989.32516012262, 8970.102989701043, 9112.407693499901, 9124.635014599407, 9126.726198214064, 9127.178553930145, 8287.709930152447, 8479.915200848005, 10699.144068474543, 10959.287646302964, 10965.287256328313, 10722.624708135218, 10764.0, 10778.083862871626, 10779.353238805697, 10779.892201077968, 10948.835767463494, 10968.670939871816, 11000.24993751562, 11042.95705294523, 11054.336739795557, 10895.128389728881, 10937.56249750009, 10962.780744385067, 10966.232363734542, 10967.067799237075, 42940.0, 42943.78528107371, 43309.56690433119, 43334.78332608317, 43354.13291734179, 3319.286353434023, 3434.6565343465454, 3448.534447849533, 3455.7926524408617, 3511.332846759133, 31226.219344516343, 31685.0, 31880.681193188004, 31938.680613193803, 31983.360332793214, 4401.581849724271, 4420.4253447052, 4434.490033646108, 4435.955640443586, 4495.5953964143055, 351877.24061379785, 352536.0, 353871.0, 354163.0, 354725.0, 34434.139146521535, 57891.71054144702, 58295.0, 58306.7084664574, 58323.70838145867, 35443.645563544036, 37915.24169516594, 37923.43114853279, 37995.0, 38037.288322025575, 13146.474141034443, 13206.471741130246, 13253.337333133497, 13290.867091329157, 13303.3348332583, 11085.889141108648, 11293.209475336811, 12110.636680899586, 12270.38648067593, 12271.263724176579, 8449.28181104613, 8653.264472519906, 8661.350398719995, 8846.336524760669, 8950.283977281802, 3380.9323813524084, 3412.2493051528622, 3413.7269018478464, 3416.257708568648, 3441.9483707744403, 957.2485598804905, 963.4412041016194, 1042.775803202315, 1052.6368402900987, 1083.6153165626247, 31975.0, 32174.517382239286, 32406.35187296288, 32508.83745581304, 32658.510122348183, 994.786120983992, 1015.720676813882, 1020.5713600287897, 1050.9962985348973, 1129.745807193375, 16110.11121192743, 17223.483295501155, 17229.483115506555, 17247.65504689899, 17344.132793360535, 542068.6621038552, 544374.0, 544476.0, 545873.0, 545979.0, 58725.412745871996, 59458.40541594615, 59475.70262148661, 59642.701786491656, 59676.104858427156, 1189.637160665988, 1214.3806658603999, 1279.2324605236784, 1307.7384523095418, 1320.7622627927065, 28773.28066798347, 32661.673383265865, 33406.66593334036, 33442.49836252507, 33980.804614849665, 50681.23978140257, 51023.74488127536, 51173.0, 51182.488175117775, 51193.23210151925, 367182.0, 369407.0, 370257.0, 370542.0, 370581.0, 28207.294817629725, 28227.2943176414, 28559.714402856538, 28937.56593651055, 29048.273793155342, 47767.522324777696, 47773.522264776904, 48114.518854811, 48386.51613483961, 48973.75513122412, 39339.46050960151, 39833.40249896312, 40430.72246995569, 41593.79203103965, 42570.787146064074, 40164.79917600393, 40382.39426408547, 40761.79619101885, 41748.165036699706, 42113.999170078794, 40211.0, 41612.58387416087, 41769.37345939874, 42120.3681944765, 42123.368149478396, 34411.82794086014, 36484.45273320849, 36600.81699591485, 36957.26085478329, 37253.25493490169, 27041.59437608398, 27147.457050859266, 27180.184594462196, 27206.727932721213, 27323.863380682968], "value": 19051.561271912968, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.1998533204949, 378.16991703211426, 383.2679581998382, 390.51576045703325, 409.4063607768736, 14357.856421435796, 18478.90760546197, 18639.1612377443, 19237.807621923777, 20425.28511502098, 15658.451954181606, 19172.616547669073, 19247.326343577977, 19331.0, 19479.902600487014, 5604.971975140119, 5869.911951320733, 5872.442117998783, 5876.9118463223085, 5876.970615146918, 5600.103983362663, 5707.343655479611, 5768.653880767168, 5778.075507918734, 5810.738516766734, 5772.971135144318, 5773.30720313563, 5778.24882765242, 5783.450572195635, 5784.421557844224, 5523.309586301712, 5561.582881283879, 5642.943570564283, 5650.237217975562, 5771.22088518051, 4997.700137991732, 5027.371578552681, 5041.823536176253, 5049.823256186016, 5052.040112378636, 23241.3027609172, 23293.650595241084, 23375.0, 23557.646635300483, 23564.29307120772, 2252.842301038928, 2318.5362927414417, 2320.918767843117, 2986.341307560213, 3127.499600063979, 13789.793153102712, 13793.724125517534, 13933.790993135111, 13999.440022399194, 14452.710945781131, 1632.6326576520187, 1673.4059408909784, 1674.4093111544082, 1681.6216351320977, 1778.2442461953747, 76879.23120768832, 79051.6047419765, 81172.18827811706, 81277.59361203216, 81418.0, 9972.650957216463, 10080.294379393372, 10110.090091891765, 10288.897111028944, 10313.174946004301, 13732.794008089886, 13754.65613359674, 13755.587332380044, 13770.104943178661, 13778.586642400744, 7654.578998155043, 7851.2148785121035, 7881.527108373516, 7916.563198410728, 8127.390445716476, 7494.5128566643, 7521.0, 7767.02912135983, 7809.453338266267, 7841.725539606086, 6253.405926436982, 6298.401651843067, 6373.521985850987, 6433.365148690975, 6455.193100862392, 1818.4635532517939, 1823.1886810369524, 1838.5771272607376, 1905.6370101074567, 1908.8186622270862, 892.2282225874571, 903.1510380242551, 912.4069354919222, 1008.9798204036025, 1090.9781804363868, 18661.720074198896, 19786.70319945202, 19915.800841991684, 19934.50163745918, 20052.498687532927, 940.6519587752575, 994.2791476179856, 997.187292356727, 1014.5130337438071, 1023.3655133816932, 10628.574857005788, 11213.495392707347, 11338.546458141582, 11470.0, 11496.597619083292, 440348.0, 441657.5845140111, 459034.0, 461780.0, 464225.0, 83227.0, 84395.15604843995, 84822.57588712017, 84987.57506212553, 85649.99999999875, 992.1368409483651, 1066.589363095214, 1085.5983286184162, 1087.923845330835, 1092.6721983405105, 16491.752623710414, 16944.57638559046, 17407.30370785205, 17685.292588296837, 17978.19098140613, 37681.24637507289, 38922.416163758135, 39006.0, 39183.0, 40120.1975960485, 51291.23063153981, 51675.74162129165, 52721.0, 52881.7355913218, 53263.20105198502, 8181.140980197231, 8188.099309076116, 8250.504969701837, 8350.749477515685, 8468.66125354979, 6478.4169424752, 9595.808083838423, 12211.26732396059, 12448.502059917502, 12516.0, 9791.608335666493, 10062.748431289276, 10585.576576936835, 10791.514381852678, 11181.440927953732, 10824.458777061272, 10963.287386319864, 11130.833037504282, 11678.70803229926, 12102.93948530287, 10261.74345641365, 10388.168946484415, 10684.626038088629, 10787.838182427427, 10834.187435942493, 8063.919360806552, 8215.260626543639, 8226.424150309422, 8562.57187140653, 8590.0, 10396.376217426978, 10568.365898046142, 10641.414722190044, 10654.680359589225, 11091.50088246047], "value": 9024.801703545652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.2613825465132, 1466.882649388049, 1503.676709507456, 1636.2555037457957, 1776.0764402510695, 7595.126560445544, 8746.300295976322, 9065.27477801776, 9066.27469802415, 9356.06439356064, 9099.63601455941, 9166.08339166083, 9172.8624070639, 9175.54122293886, 9178.128077832594, 2349.4361353275217, 2375.3111597636666, 2382.0471811275456, 2383.7854593086618, 2384.9284521464383, 2297.632378819389, 2300.2639155470256, 2345.6950596422525, 2347.084636991575, 2349.060375849657, 2307.792298693117, 2320.1299512682745, 2325.662778897059, 2359.457324815285, 2360.24472168906, 2246.33733048751, 2262.7171603549555, 2273.8976746046383, 2278.4076140203583, 2312.90748370065, 2286.931392058241, 2297.276357947241, 2297.414159389345, 2302.504961433283, 2307.573098976695, 9031.458112513204, 9079.092090790868, 9134.406263592846, 9141.177294043568, 9146.634134634607, 5378.924215156984, 5481.616286859921, 5484.396716361197, 5490.286262785852, 5554.333479982388, 3410.437277849152, 3419.606745224306, 3475.2528206435486, 3487.668671476206, 3497.1431999160113, 1119.9944000280027, 1128.9322640641587, 1129.9604513841975, 1137.4653912661036, 1149.408054851752, 12877.420516076705, 12939.48242070316, 12940.547080852126, 12974.610761677164, 12980.480780768758, 4426.336049592587, 4613.653975951817, 4661.091087237956, 4781.187198176319, 4781.617470602413, 10018.849717254247, 10039.548220330104, 10041.397516148909, 10053.497325133714, 10054.396736195851, 5208.270842082112, 5215.269862219292, 5216.895662086737, 5216.921746173811, 5218.66078704883, 4304.849330273488, 4438.201123797748, 4826.831060912851, 4873.415190177201, 4930.704157750546, 3804.144067584771, 3828.617138286205, 3898.8245528951265, 3924.8822535323984, 3946.1910308386714, 1158.7276989907534, 1179.4987130469517, 1194.802857528498, 1198.9460474278678, 1205.727204220034, 769.7113582406597, 824.4970567953544, 879.9792241000537, 902.0393281155548, 925.833349997007, 5787.0, 5840.795572155039, 5914.817036592699, 5951.613145145551, 6231.626102433868, 893.9865902011475, 932.8460803967405, 945.1163162443138, 960.7790208252002, 993.2153598657125, 3897.5322961244265, 4108.281050816096, 4132.050067321622, 4155.667546596265, 4245.660347172219, 11269.718257043476, 11592.710182245342, 11595.362255076052, 11596.130290228099, 11601.883981160248, 11464.713382165346, 11470.254433461798, 11472.426378681033, 11482.0, 11548.884511154782, 8483.109273526314, 8486.830263394697, 8492.787680307918, 8544.31645468361, 8574.099719529484, 5763.193152958505, 5763.221965034667, 5890.204822348928, 5945.464908158287, 6035.758569657164, 9632.711018669452, 10422.843657345298, 10471.3193642413, 10501.737456563342, 10557.049865512141, 10089.445080520642, 10253.28227024131, 10337.017983291575, 10403.115735162595, 10412.427316497666, 6992.3357281058215, 7033.296670332926, 7529.360004399688, 8056.637451314587, 8233.259006689426, 8455.873161902453, 8686.609102590272, 8769.91230087691, 8779.25376343018, 8779.429337093068, 7539.924600753922, 7624.237576242332, 7801.648925798466, 7839.9216007839195, 7928.603569821601, 7578.393728501596, 7724.691012359667, 7766.378689704923, 7771.92228077712, 7984.321332686786, 6515.511336649675, 6605.174353205849, 6626.403623673892, 6627.138471998609, 6632.104665870046, 4705.270683044134, 4748.857534273978, 4758.524147585353, 4845.370101886732, 4939.629527785502, 4855.854324370274, 4963.801447942042, 5049.2426136079885, 5069.5944324454695, 5091.0], "value": 4639.189619501852, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.21105342171285, 320.94383482890487, 330.1992667780632, 334.0607771884211, 334.74224846867895, 4932.432770231425, 5262.342207224097, 5287.39194992576, 5292.015494189679, 5304.761285742136, 5249.1601343785005, 5265.156815319723, 5274.868128296795, 5288.0, 5307.469253074691, 4134.6485548728315, 4174.499060112776, 4178.143480586473, 4182.769947652878, 4192.203481338536, 3910.7262491625456, 4129.6076872697195, 4170.998960249533, 4176.1230141670285, 4210.221109094828, 4077.1845630873795, 4091.856785012525, 4093.672506199497, 4095.5699651536606, 4100.056986893016, 4045.9797701011603, 4088.9795551022203, 4094.406311084891, 4098.057446787256, 4099.262132816092, 3599.2981368633127, 3616.674499295076, 3617.0776452004834, 3628.657396763188, 3642.3079614873354, 5077.543021128116, 5132.769025393866, 5137.537621614073, 5140.820071297487, 5189.636725429222, 4642.721436713808, 4647.95352046482, 4648.651351148677, 4660.557247061523, 4661.207594708908, 5438.592105592096, 5443.727813609344, 5457.617966742329, 5461.808836690737, 5510.8071217507595, 3768.4158955361963, 3897.7856217908, 3913.5344732818426, 3923.333033384303, 3929.5284565851994, 5412.783488660449, 5555.638883472561, 5659.0, 5693.658380497183, 5702.429757024264, 3868.419737039467, 3871.6128387161057, 3872.1868407634147, 3941.034446560611, 4142.192272506893, 4142.647874930604, 4148.377743338523, 4151.6886233532005, 4151.813168407429, 4185.895352616148, 4629.96281636079, 4697.577218050392, 4707.964247865464, 4730.432348118248, 4746.691465054761, 5490.533304669148, 5492.752826122834, 5515.420880807457, 5532.612717109763, 5546.972265138648, 1420.8579142085916, 1438.4030627289558, 1439.755241608929, 1440.3158499712554, 1441.704450587627, 892.5314210039651, 923.8244733500748, 946.186279799372, 956.3018996132723, 961.0437614573405, 4650.070220858373, 4677.812887484531, 4720.362751028636, 4733.5266473352385, 4801.68789028712, 4921.5078492151215, 4961.3798275215595, 4990.700557966534, 4995.825146119869, 5007.572841740087, 4755.025219829927, 4756.357891684647, 4769.427668679711, 4781.378420805343, 5099.64302498829, 2951.7343439090587, 3005.9549106763416, 3023.365093330381, 3026.334206474572, 3038.3771326877936, 5897.469227769521, 5899.734511946973, 5922.851928701731, 5998.820035398945, 6002.459778619946, 5579.9163012554845, 5779.393163717833, 5783.624064435799, 5786.594938354359, 5866.5893387462465, 5882.735276912549, 5889.263842019747, 5889.705514724246, 5890.705464726747, 5926.555508336978, 5398.514133727984, 5632.014397480507, 5644.294463192101, 5730.484256416942, 5752.568557358131, 5570.164475328733, 5578.916316255179, 5580.637258578179, 5604.215409842707, 5611.793464405173, 5624.775008999758, 5679.290088738908, 5816.185733997159, 5885.352611212848, 5895.380984996514, 4081.5510293868247, 4088.816003279919, 4089.775062371479, 4090.877273681794, 4113.0, 5669.376368599534, 5684.914726279027, 5689.089745640677, 5716.342620598684, 5734.569907256889, 5330.614040349459, 5546.473085056913, 5551.25058117149, 5579.609427340048, 5614.550835933197, 5534.782347883459, 5548.361938377138, 5556.722163891708, 5572.60991730575, 5573.9343361150395, 5386.5152136307925, 5583.297094386251, 5589.916151257816, 5625.887482250414, 5628.409017053151, 5546.473085056913, 5575.107982722745, 5585.748641311069, 5616.522595579258, 5617.297837770278, 5468.261784659179, 5475.945240547543, 5522.779088836402, 5537.335519737658, 5543.1685247213245], "value": 4182.033710593727, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.5496549759906, 714.1430283659608, 760.228368206271, 774.9186335434781, 782.6438970268529, 14422.927885360572, 15554.922225388886, 15594.688106237869, 15685.764713529292, 15692.372305107796, 13082.345882705851, 13104.213747175176, 13156.934215328934, 13164.736705265888, 13187.142835715671, 659.7921654678761, 678.131991051455, 749.4641331448014, 784.8822676598498, 805.2149154574302, 782.0498094814799, 783.3185128937832, 789.2226157235148, 790.1032328307355, 790.6876783670447, 831.8294749576322, 837.9664813407456, 839.03091928822, 839.9328053755715, 840.1850205300848, 684.2165720250335, 702.4696354252558, 829.9419040667156, 831.4844796226348, 831.5925196653657, 599.691159053088, 607.5838050935124, 608.6713174885557, 616.202018386191, 622.6419808610067, 14217.360218790076, 14224.0, 14245.572632821031, 14246.430142794276, 14278.500252491112, 12579.937100314532, 13053.34733263333, 13481.123726957716, 13577.253251071186, 13857.514986975408, 11908.58319958806, 12017.278963262232, 12062.939685301604, 12096.879031209752, 12385.876141238652, 4145.958540414617, 4215.915681686349, 4223.345381465878, 4232.576742325774, 4283.828646854122, 62485.37514624841, 64730.90863642045, 64778.0, 74068.0, 74271.0, 10689.465526723632, 10785.191110666574, 10829.945850270698, 11022.283551569122, 11026.889731102747, 20988.37034888956, 21051.68422473664, 21065.157393704387, 21066.262680806405, 21070.47323816917, 8182.140875208018, 8204.384671149688, 8288.46125001885, 8464.915350846413, 8587.05542390349, 4194.2869712149, 4274.081072569413, 4303.074838909587, 4358.455193100863, 4470.0, 3674.0998455378603, 3784.5836957934607, 3813.828377723009, 3824.4263360496147, 3835.060410199463, 640.7276907314373, 652.3248437866785, 661.7584581627767, 662.483263054818, 676.6616691654173, 655.9639219842859, 733.1078893764156, 741.6440108747723, 745.4297462441236, 748.1396394146775, 8808.823823523493, 8946.463212207289, 9247.861282080774, 9376.249900007982, 9463.148316651534, 595.3243069116497, 712.28059659743, 741.458735123363, 746.8431629357896, 750.673968195651, 4273.87178384649, 4325.3728209409455, 4652.325412815123, 4654.953450465519, 4656.534346565384, 115714.0, 120892.0, 121679.0, 123122.0, 123155.0, 102111.0, 108200.0, 109931.0, 112198.43900780607, 113900.43049784697, 72973.0, 74107.25892741111, 90995.54502227447, 94525.5273723627, 96183.0, 9257.0, 9419.199368053793, 9469.195118415011, 9471.242300616072, 9718.465484398426, 47533.76233118951, 49150.0, 49945.0, 51183.74408127936, 53926.460735392146, 73179.63410182916, 74142.62928685537, 77106.0, 78011.60994194992, 79782.2021779798, 12271.447784849524, 12340.938295308466, 12395.256284622952, 12573.789258871913, 13243.139195952232, 12960.870391295966, 13374.465021399035, 13735.656608584864, 13876.722465550834, 14202.502912398017, 11121.277116987694, 11227.157963152633, 11321.490532926204, 11751.471183796579, 11757.294562326288, 12816.0, 13002.414891330101, 13248.337583120612, 13312.0, 13585.52450664222, 11841.23032002917, 12152.817707734568, 12181.939090304491, 12418.75162496763, 13091.214527128403, 10776.407297598707, 10899.782004360028, 11027.44862756875, 11557.42212889369, 11660.0, 10125.696229113139, 10544.630937917136, 10872.075873550837, 10952.397618131079, 11069.225154239126], "value": 7356.793881112174, "unit": "score"}], "name": "dromaeo_css", "value": 7088.881311527291, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 112.0, 114.0, 113.0, 115.0, 111.0, 112.0, 113.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 116.0, 156.0, 121.0, 121.0, 122.0, 116.0, 144.0, 118.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 160.0, 161.0, 160.0, 161.0, 162.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 76.0, 108.0, 76.0, 76.0, 76.0, 76.0, 109.0, 75.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 107.0, 83.0, 83.0, 83.0, 100.0, 82.0, 82.0, 113.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 147.0, 145.0, 145.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 52.0, 52.0, 53.0, 52.0, 52.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 59.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 79.0, 84.0, 79.0, 79.0, 84.0, 78.0, 78.0, 83.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 122.0, 124.0, 124.0, 132.0, 133.0, 124.0, 124.0, 156.0, 133.0], "value": 129.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 172.0, 169.0, 163.0, 163.0, 165.0, 162.0, 166.0, 163.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 68.0, 64.0, 65.0, 64.0, 65.0, 65.0, 69.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1484.4, "alertThreshold": 2.0}]} 19:01:44 INFO - Return code: 0 19:01:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:01:45 INFO - # TBPL SUCCESS # 19:01:45 INFO - Running post-action listener: _resource_record_post_action 19:01:45 INFO - [mozharness: 2016-09-30 02:01:45.019831Z] Finished run-tests step (success) 19:01:45 INFO - Running post-run listener: _resource_record_post_run 19:01:45 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392814592; Read time: 16; Write time: 328832 19:01:45 INFO - TinderboxPrint: CPU usage
13.0% 19:01:45 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:01:45 INFO - TinderboxPrint: I/O write bytes / time
392,814,592 / 328,832 19:01:45 INFO - TinderboxPrint: CPU idle
8,294.0 (87.0%) 19:01:45 INFO - TinderboxPrint: CPU user
1,191.2 (12.5%) 19:01:45 INFO - TinderboxPrint: Swap in / out
0 / 0 19:01:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103923712; Read time: 0; Write time: 108944 19:01:45 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288890880; Read time: 0; Write time: 219888 19:01:45 INFO - Running post-run listener: _upload_blobber_files 19:01:45 INFO - Blob upload gear active. 19:01:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:01:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:01:45 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:01:45 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:01:46 INFO - (blobuploader) - INFO - Open directory for files ... 19:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:01:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:46 INFO - (blobuploader) - INFO - Done attempting. 19:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:01:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:52 INFO - (blobuploader) - INFO - Done attempting. 19:01:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:01:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:01:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:53 INFO - (blobuploader) - INFO - Done attempting. 19:01:53 INFO - (blobuploader) - INFO - Iteration through files over. 19:01:53 INFO - Return code: 0 19:01:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:01:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:01:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2bdda1d314c3b93f42dc0822039da79d0e298ecc50778b7bcbd15a442fc9d65d7803707e53950c7bd12daa5484a3ac8cbe7986f66ac6751601fe0bf49b49c0d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6568256c4893f019add87e41c3cd545bf5719c4bacef330b0017a8d4b0289c589d297de7b7a0b2441489804cf9b0b2e629340e39df96891b4db574621ac0ad0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5309a39b2634c776a0fb5030fe21139d79ac9b4f4ffc7ca9b9399f4d4adadf165169e7616c1f25d45cf6b9fe5707132569dc4137b964158b98465341e3ae3dcb"} 19:01:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:01:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:01:53 INFO - Contents: 19:01:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2bdda1d314c3b93f42dc0822039da79d0e298ecc50778b7bcbd15a442fc9d65d7803707e53950c7bd12daa5484a3ac8cbe7986f66ac6751601fe0bf49b49c0d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6568256c4893f019add87e41c3cd545bf5719c4bacef330b0017a8d4b0289c589d297de7b7a0b2441489804cf9b0b2e629340e39df96891b4db574621ac0ad0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5309a39b2634c776a0fb5030fe21139d79ac9b4f4ffc7ca9b9399f4d4adadf165169e7616c1f25d45cf6b9fe5707132569dc4137b964158b98465341e3ae3dcb"} 19:01:53 INFO - Running post-run listener: copy_logs_to_upload_dir 19:01:53 INFO - Copying logs to upload dir... 19:01:53 INFO - mkdir: /builds/slave/test/build/upload/logs 19:01:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.837920 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-29 19:01:53.091815) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 19:01:53.095042) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2bdda1d314c3b93f42dc0822039da79d0e298ecc50778b7bcbd15a442fc9d65d7803707e53950c7bd12daa5484a3ac8cbe7986f66ac6751601fe0bf49b49c0d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6568256c4893f019add87e41c3cd545bf5719c4bacef330b0017a8d4b0289c589d297de7b7a0b2441489804cf9b0b2e629340e39df96891b4db574621ac0ad0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5309a39b2634c776a0fb5030fe21139d79ac9b4f4ffc7ca9b9399f4d4adadf165169e7616c1f25d45cf6b9fe5707132569dc4137b964158b98465341e3ae3dcb"} build_url:https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011585 build_url: 'https://queue.taskcluster.net/v1/task/HD2wQ_X-QiuSBZdLyyn1Mg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2bdda1d314c3b93f42dc0822039da79d0e298ecc50778b7bcbd15a442fc9d65d7803707e53950c7bd12daa5484a3ac8cbe7986f66ac6751601fe0bf49b49c0d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6568256c4893f019add87e41c3cd545bf5719c4bacef330b0017a8d4b0289c589d297de7b7a0b2441489804cf9b0b2e629340e39df96891b4db574621ac0ad0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5309a39b2634c776a0fb5030fe21139d79ac9b4f4ffc7ca9b9399f4d4adadf165169e7616c1f25d45cf6b9fe5707132569dc4137b964158b98465341e3ae3dcb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 19:01:53.120397) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 19:01:53.120737) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475199670.919174-544149197 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 19:01:53.206006) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 19:01:53.206382) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 19:01:53.206786) ========= ========= Total master_lag: 0.17 =========