builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-080 starttime: 1489613750.14 results: success (0) revision: 1c4d97d1bc61468c82e55a09bab660e52bb2e9b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.140723) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.141160) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.141427) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.156539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.156930) ========= 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=781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-15 14:35:50-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.9M=0s 2017-03-15 14:35:51 (34.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.352216 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.522764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.523114) ========= 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=781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017626 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:50.572967) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-15 14:35:50.573383) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1c4d97d1bc61468c82e55a09bab660e52bb2e9b5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 1c4d97d1bc61468c82e55a09bab660e52bb2e9b5 --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=781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-15 14:35:51,415 truncating revision to first 12 chars 2017-03-15 14:35:51,415 Setting DEBUG logging. 2017-03-15 14:35:51,415 attempt 1/10 2017-03-15 14:35:51,415 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1c4d97d1bc61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-15 14:35:52,429 unpacking tar archive at: mozilla-central-1c4d97d1bc61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.283986 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-15 14:35:51.871192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:51.871722) ========= script_repo_revision: 1c4d97d1bc61468c82e55a09bab660e52bb2e9b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:51.872282) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:51.872730) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-15 14:35:51.882997) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-15 14:35:51.883366) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:35:52 INFO - MultiFileLogger online at 20170315 14:35:52 in /builds/slave/test 14:35:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:35:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:35:52 INFO - {'append_to_log': False, 14:35:52 INFO - 'base_work_dir': '/builds/slave/test', 14:35:52 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:35:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:35:52 INFO - 'branch': 'Firefox-Non-PGO', 14:35:52 INFO - 'buildbot_json_path': 'buildprops.json', 14:35:52 INFO - 'config_files': ('talos/linux_config.py',), 14:35:52 INFO - 'default_actions': ('clobber', 14:35:52 INFO - 'read-buildbot-config', 14:35:52 INFO - 'download-and-extract', 14:35:52 INFO - 'populate-webroot', 14:35:52 INFO - 'create-virtualenv', 14:35:52 INFO - 'install', 14:35:52 INFO - 'run-tests'), 14:35:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:35:52 INFO - 'download_minidump_stackwalk': True, 14:35:52 INFO - 'download_symbols': 'ondemand', 14:35:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:35:52 INFO - 'tooltool.py': '/tools/tooltool.py', 14:35:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:35:52 INFO - '/tools/misc-python/virtualenv.py')}, 14:35:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:35:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:35:52 INFO - 'gecko_profile': False, 14:35:52 INFO - 'gecko_profile_interval': 0, 14:35:52 INFO - 'installer_path': 'installer.exe', 14:35:52 INFO - 'log_level': 'info', 14:35:52 INFO - 'log_name': 'talos', 14:35:52 INFO - 'log_to_console': True, 14:35:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:35:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:35:52 INFO - 'opt_config_files': (), 14:35:52 INFO - 'pip_index': False, 14:35:52 INFO - 'suite': 'dromaeojs-e10s', 14:35:52 INFO - 'system_bits': '32', 14:35:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:35:52 INFO - 'title': 'talos-linux64-ix-080', 14:35:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:35:52 INFO - 'use_talos_json': True, 14:35:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:35:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:35:52 INFO - 'work_dir': 'build'} 14:35:52 INFO - [mozharness: 2017-03-15 21:35:52.822269Z] Running clobber step. 14:35:52 INFO - Running pre-action listener: _resource_record_pre_action 14:35:52 INFO - Running main action method: clobber 14:35:52 INFO - rmtree: /builds/slave/test/build 14:35:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:35:53 INFO - Running post-action listener: _resource_record_post_action 14:35:53 INFO - [mozharness: 2017-03-15 21:35:53.827241Z] Finished clobber step (success) 14:35:53 INFO - [mozharness: 2017-03-15 21:35:53.827342Z] Running read-buildbot-config step. 14:35:53 INFO - Running pre-action listener: _resource_record_pre_action 14:35:53 INFO - Running main action method: read_buildbot_config 14:35:53 INFO - Using buildbot properties: 14:35:53 INFO - { 14:35:53 INFO - "project": "", 14:35:53 INFO - "product": "firefox", 14:35:53 INFO - "who": "kwierso@gmail.com", 14:35:53 INFO - "installer_path": "public/build/target.tar.bz2", 14:35:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:35:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:35:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:35:53 INFO - "stage_platform": "linux64", 14:35:53 INFO - "basedir": "/builds/slave/test", 14:35:53 INFO - "buildnumber": 465, 14:35:53 INFO - "platform": "ubuntu64_hw", 14:35:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:35:53 INFO - "slavebuilddir": "test", 14:35:53 INFO - "taskId": "Y-xB6yXlRI-mOMAa9iyRgA", 14:35:53 INFO - "branch": "mozilla-central", 14:35:53 INFO - "script_repo_revision": "production", 14:35:53 INFO - "revision": "1c4d97d1bc61468c82e55a09bab660e52bb2e9b5", 14:35:53 INFO - "slavename": "talos-linux64-ix-080", 14:35:53 INFO - "repo_path": "mozilla-central" 14:35:53 INFO - } 14:35:53 INFO - Finding installer, test and symbols from parent task. 14:35:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y-xB6yXlRI-mOMAa9iyRgA'}, attempt #1 14:35:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y-xB6yXlRI-mOMAa9iyRgA'}, attempt #1 14:35:54 INFO - Task dependencies: d9EGGQ74Qc-2LM-H7UPtog 14:35:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog'}, attempt #1 14:35:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2 14:35:55 INFO - Set test_url: https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/test_packages.json 14:35:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.crashreporter-symbols.zip 14:35:55 INFO - Running post-action listener: _resource_record_post_action 14:35:55 INFO - [mozharness: 2017-03-15 21:35:55.229584Z] Finished read-buildbot-config step (success) 14:35:55 INFO - [mozharness: 2017-03-15 21:35:55.229800Z] Running download-and-extract step. 14:35:55 INFO - Running pre-action listener: _resource_record_pre_action 14:35:55 INFO - Running main action method: download_and_extract 14:35:55 INFO - mkdir: /builds/slave/test/build/tests 14:35:55 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')]} 14:35:55 INFO - trying https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.test_packages.json 14:35:55 INFO - Downloading https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:35:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:35:55 INFO - Downloaded 896 bytes. 14:35:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:35:55 INFO - Using the following test package requirements: 14:35:55 INFO - {u'common': [u'target.common.tests.zip'], 14:35:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:35:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:35:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:35:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:35:55 INFO - u'mozbase': [u'target.common.tests.zip'], 14:35:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:35:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:35:55 INFO - u'web-platform': [u'target.common.tests.zip', 14:35:55 INFO - u'target.web-platform.tests.zip'], 14:35:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:35:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:35:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.common.tests.zip 14:35:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:35:55 INFO - Fetch https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.common.tests.zip into memory 14:35:56 INFO - Expected file size: 58185715 14:35:56 INFO - Obtained file size: 58185715 14:36:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:36:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.common.tests.zip 14:36:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:36:06 INFO - Fetch https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.common.tests.zip into memory 14:36:07 INFO - Expected file size: 58185715 14:36:07 INFO - Obtained file size: 58185715 14:36:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.talos.tests.zip 14:36:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:36:18 INFO - Fetch https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.talos.tests.zip into memory 14:36:19 INFO - Expected file size: 14404452 14:36:19 INFO - Obtained file size: 14404452 14:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:19 INFO - trying https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2 14:36:19 INFO - Downloading https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:36:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:36:20 INFO - Downloaded 61747410 bytes. 14:36:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2 14:36:20 INFO - mkdir: /builds/slave/test/properties 14:36:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:36:20 INFO - Writing to file /builds/slave/test/properties/build_url 14:36:20 INFO - Contents: 14:36:20 INFO - build_url:https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2 14:36:20 INFO - Running post-action listener: _resource_record_post_action 14:36:20 INFO - Running post-action listener: set_extra_try_arguments 14:36:20 INFO - [mozharness: 2017-03-15 21:36:20.859083Z] Finished download-and-extract step (success) 14:36:20 INFO - [mozharness: 2017-03-15 21:36:20.859179Z] Running populate-webroot step. 14:36:20 INFO - Running pre-action listener: _resource_record_pre_action 14:36:20 INFO - Running main action method: populate_webroot 14:36:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:36:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:36:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:36:20 INFO - '--tppagecycles', 14:36:20 INFO - '7'], 14:36:20 INFO - 'tests': ['tsvgm']}}, 14:36:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:36:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:36:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:36:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:36:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:36:20 INFO - 'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:36:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:36:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:36:20 INFO - 'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['damp', 'tps']}, 14:36:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:36:20 INFO - 'tests': ['damp', 'tps']}, 14:36:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['dromaeo_dom']}, 14:36:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:36:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['basic_compositor_video']}, 14:36:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:36:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['a11yr', 14:36:20 INFO - 'ts_paint', 14:36:20 INFO - 'tpaint', 14:36:20 INFO - 'sessionrestore', 14:36:20 INFO - 'sessionrestore_no_auto_restore', 14:36:20 INFO - 'tabpaint']}, 14:36:20 INFO - 'other-e10s': {'tests': ['a11yr', 14:36:20 INFO - 'ts_paint', 14:36:20 INFO - 'tpaint', 14:36:20 INFO - 'sessionrestore', 14:36:20 INFO - 'sessionrestore_no_auto_restore', 14:36:20 INFO - 'tabpaint']}, 14:36:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:36:20 INFO - 'ts_paint', 14:36:20 INFO - 'tpaint', 14:36:20 INFO - 'sessionrestore', 14:36:20 INFO - 'sessionrestore_no_auto_restore', 14:36:20 INFO - 'tabpaint']}, 14:36:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:36:20 INFO - 'ts_paint', 14:36:20 INFO - 'tpaint', 14:36:20 INFO - 'sessionrestore', 14:36:20 INFO - 'sessionrestore_no_auto_restore', 14:36:20 INFO - 'tabpaint']}, 14:36:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['a11yr', 14:36:20 INFO - 'ts_paint', 14:36:20 INFO - 'tpaint', 14:36:20 INFO - 'sessionrestore', 14:36:20 INFO - 'sessionrestore_no_auto_restore', 14:36:20 INFO - 'tabpaint']}, 14:36:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['a11yr', 14:36:20 INFO - 'ts_paint', 14:36:20 INFO - 'tpaint', 14:36:20 INFO - 'sessionrestore', 14:36:20 INFO - 'sessionrestore_no_auto_restore', 14:36:20 INFO - 'tabpaint']}, 14:36:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['tsvgx', 14:36:20 INFO - 'tsvgr_opacity', 14:36:20 INFO - 'tart', 14:36:20 INFO - 'tscrollx', 14:36:20 INFO - 'cart', 14:36:20 INFO - 'tsvg_static']}, 14:36:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:36:20 INFO - 'tsvgr_opacity', 14:36:20 INFO - 'tart', 14:36:20 INFO - 'tscrollx', 14:36:20 INFO - 'cart', 14:36:20 INFO - 'tsvg_static']}, 14:36:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:36:20 INFO - 'talos_options': ['--disable-e10s'], 14:36:20 INFO - 'tests': ['tp5o']}, 14:36:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:36:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:36:20 INFO - 'talos_options': ['--disable-e10s', 14:36:20 INFO - '--xperf_path', 14:36:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:20 INFO - 'tests': ['tp5n']}, 14:36:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:36:20 INFO - 'talos_options': ['--xperf_path', 14:36:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:36:20 INFO - 'tests': ['tp5n']}}, 14:36:20 INFO - 'talos.zip': {'path': '', 14:36:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:36:20 INFO - Running post-action listener: _resource_record_post_action 14:36:20 INFO - [mozharness: 2017-03-15 21:36:20.872283Z] Finished populate-webroot step (success) 14:36:20 INFO - [mozharness: 2017-03-15 21:36:20.872373Z] Running create-virtualenv step. 14:36:20 INFO - Running pre-action listener: _resource_record_pre_action 14:36:20 INFO - Running main action method: create_virtualenv 14:36:20 INFO - Creating virtualenv /builds/slave/test/build/venv 14:36:20 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 14:36:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:36:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:36:20 INFO - Using real prefix '/usr' 14:36:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:36:21 INFO - Installing distribute.............................................................................................................................................................................................done. 14:36:23 INFO - Installing pip.................done. 14:36:23 INFO - Return code: 0 14:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:36:23 INFO - Reading from file tmpfile_stdout 14:36:23 INFO - Output received: 14:36:23 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) 14:36:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:36:23 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')]} 14:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:23 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')]} 14:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:23 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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:23 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 14:36:23 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 14:36:23 INFO - Using env: {'DISPLAY': ':0', 14:36:23 INFO - 'HOME': '/home/cltbld', 14:36:23 INFO - 'LANG': 'en_US.UTF-8', 14:36:23 INFO - 'LANGUAGE': 'en_US:en', 14:36:23 INFO - 'LOGNAME': 'cltbld', 14:36:23 INFO - 'MAIL': '/var/mail/cltbld', 14:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:23 INFO - 'MOZ_NO_REMOTE': '1', 14:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:23 INFO - 'NO_EM_RESTART': '1', 14:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:23 INFO - 'PWD': '/builds/slave/test', 14:36:23 INFO - 'SHELL': '/bin/bash', 14:36:23 INFO - 'SHLVL': '1', 14:36:23 INFO - 'TERM': 'linux', 14:36:23 INFO - 'TMOUT': '86400', 14:36:23 INFO - 'USER': 'cltbld', 14:36:23 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:23 INFO - '_': '/tools/buildbot/bin/python'} 14:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:23 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 14:36:23 INFO - Cleaning up... 14:36:23 INFO - Return code: 0 14:36:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:36:23 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')]} 14:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:23 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')]} 14:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:23 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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:23 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 14:36:23 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 14:36:23 INFO - Using env: {'DISPLAY': ':0', 14:36:23 INFO - 'HOME': '/home/cltbld', 14:36:23 INFO - 'LANG': 'en_US.UTF-8', 14:36:23 INFO - 'LANGUAGE': 'en_US:en', 14:36:23 INFO - 'LOGNAME': 'cltbld', 14:36:23 INFO - 'MAIL': '/var/mail/cltbld', 14:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:23 INFO - 'MOZ_NO_REMOTE': '1', 14:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:23 INFO - 'NO_EM_RESTART': '1', 14:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:23 INFO - 'PWD': '/builds/slave/test', 14:36:23 INFO - 'SHELL': '/bin/bash', 14:36:23 INFO - 'SHLVL': '1', 14:36:23 INFO - 'TERM': 'linux', 14:36:23 INFO - 'TMOUT': '86400', 14:36:23 INFO - 'USER': 'cltbld', 14:36:23 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:23 INFO - '_': '/tools/buildbot/bin/python'} 14:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:24 INFO - Downloading/unpacking psutil>=3.1.1 14:36:24 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 14:36:24 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 14:36:24 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 14:36:24 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 14:36:24 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 14:36:24 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 14:36:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:36:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:26 INFO - Installing collected packages: psutil 14:36:26 INFO - Running setup.py install for psutil 14:36:26 INFO - building 'psutil._psutil_linux' extension 14:36:26 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 14:36:26 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 14:36:26 INFO - building 'psutil._psutil_posix' extension 14:36:26 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 14:36:26 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 14:36:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:36:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:36:26 INFO - Successfully installed psutil 14:36:26 INFO - Cleaning up... 14:36:26 INFO - Return code: 0 14:36:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:36:26 INFO - Using env: {'DISPLAY': ':0', 14:36:26 INFO - 'HOME': '/home/cltbld', 14:36:26 INFO - 'LANG': 'en_US.UTF-8', 14:36:26 INFO - 'LANGUAGE': 'en_US:en', 14:36:26 INFO - 'LOGNAME': 'cltbld', 14:36:26 INFO - 'MAIL': '/var/mail/cltbld', 14:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:26 INFO - 'MOZ_NO_REMOTE': '1', 14:36:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:26 INFO - 'NO_EM_RESTART': '1', 14:36:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:26 INFO - 'PWD': '/builds/slave/test', 14:36:26 INFO - 'SHELL': '/bin/bash', 14:36:26 INFO - 'SHLVL': '1', 14:36:26 INFO - 'TERM': 'linux', 14:36:26 INFO - 'TMOUT': '86400', 14:36:26 INFO - 'USER': 'cltbld', 14:36:26 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:26 INFO - '_': '/tools/buildbot/bin/python'} 14:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:36:27 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 14:36:27 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 14:36:27 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 14:36:27 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 14:36:27 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 14:36:27 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 14:36:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:36:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:36:29 INFO - Installing collected packages: mozsystemmonitor 14:36:29 INFO - Running setup.py install for mozsystemmonitor 14:36:29 INFO - Successfully installed mozsystemmonitor 14:36:29 INFO - Cleaning up... 14:36:29 INFO - Return code: 0 14:36:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:36:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:36:29 INFO - Using env: {'DISPLAY': ':0', 14:36:29 INFO - 'HOME': '/home/cltbld', 14:36:29 INFO - 'LANG': 'en_US.UTF-8', 14:36:29 INFO - 'LANGUAGE': 'en_US:en', 14:36:29 INFO - 'LOGNAME': 'cltbld', 14:36:29 INFO - 'MAIL': '/var/mail/cltbld', 14:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:29 INFO - 'MOZ_NO_REMOTE': '1', 14:36:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:29 INFO - 'NO_EM_RESTART': '1', 14:36:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:29 INFO - 'PWD': '/builds/slave/test', 14:36:29 INFO - 'SHELL': '/bin/bash', 14:36:29 INFO - 'SHLVL': '1', 14:36:29 INFO - 'TERM': 'linux', 14:36:29 INFO - 'TMOUT': '86400', 14:36:29 INFO - 'USER': 'cltbld', 14:36:29 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:29 INFO - '_': '/tools/buildbot/bin/python'} 14:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:29 INFO - Downloading/unpacking jsonschema==2.5.1 14:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:36:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:36:31 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 14:36:31 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 14:36:31 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 14:36:31 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 14:36:31 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 14:36:31 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 14:36:32 INFO - Downloading functools32-3.2.3-2.tar.gz 14:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:36:32 INFO - warning: no files found matching '*.txt' 14:36:32 INFO - no previously-included directories found matching 'build' 14:36:32 INFO - no previously-included directories found matching 'dist' 14:36:32 INFO - no previously-included directories found matching '.git*' 14:36:32 INFO - Installing collected packages: jsonschema, functools32 14:36:32 INFO - Running setup.py install for functools32 14:36:32 INFO - warning: no files found matching '*.txt' 14:36:32 INFO - no previously-included directories found matching 'build' 14:36:32 INFO - no previously-included directories found matching 'dist' 14:36:32 INFO - no previously-included directories found matching '.git*' 14:36:32 INFO - Successfully installed jsonschema functools32 14:36:32 INFO - Cleaning up... 14:36:32 INFO - Return code: 0 14:36:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:36:32 INFO - Using env: {'DISPLAY': ':0', 14:36:32 INFO - 'HOME': '/home/cltbld', 14:36:32 INFO - 'LANG': 'en_US.UTF-8', 14:36:32 INFO - 'LANGUAGE': 'en_US:en', 14:36:32 INFO - 'LOGNAME': 'cltbld', 14:36:32 INFO - 'MAIL': '/var/mail/cltbld', 14:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:32 INFO - 'MOZ_NO_REMOTE': '1', 14:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:32 INFO - 'NO_EM_RESTART': '1', 14:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:32 INFO - 'PWD': '/builds/slave/test', 14:36:32 INFO - 'SHELL': '/bin/bash', 14:36:32 INFO - 'SHLVL': '1', 14:36:32 INFO - 'TERM': 'linux', 14:36:32 INFO - 'TMOUT': '86400', 14:36:32 INFO - 'USER': 'cltbld', 14:36:32 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:32 INFO - '_': '/tools/buildbot/bin/python'} 14:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:36:32 INFO - Cleaning up... 14:36:32 INFO - Return code: 0 14:36:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:36:32 INFO - Using env: {'DISPLAY': ':0', 14:36:32 INFO - 'HOME': '/home/cltbld', 14:36:32 INFO - 'LANG': 'en_US.UTF-8', 14:36:32 INFO - 'LANGUAGE': 'en_US:en', 14:36:32 INFO - 'LOGNAME': 'cltbld', 14:36:32 INFO - 'MAIL': '/var/mail/cltbld', 14:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:32 INFO - 'MOZ_NO_REMOTE': '1', 14:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:32 INFO - 'NO_EM_RESTART': '1', 14:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:32 INFO - 'PWD': '/builds/slave/test', 14:36:32 INFO - 'SHELL': '/bin/bash', 14:36:32 INFO - 'SHLVL': '1', 14:36:32 INFO - 'TERM': 'linux', 14:36:32 INFO - 'TMOUT': '86400', 14:36:32 INFO - 'USER': 'cltbld', 14:36:32 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:32 INFO - '_': '/tools/buildbot/bin/python'} 14:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:32 INFO - Downloading/unpacking blobuploader==1.2.4 14:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:34 INFO - Downloading blobuploader-1.2.4.tar.gz 14:36:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:36:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:36:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36:35 INFO - Downloading docopt-0.6.1.tar.gz 14:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:36:35 INFO - Installing collected packages: blobuploader, requests, docopt 14:36:35 INFO - Running setup.py install for blobuploader 14:36:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:36:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:36:35 INFO - Running setup.py install for requests 14:36:35 INFO - Running setup.py install for docopt 14:36:36 INFO - Successfully installed blobuploader requests docopt 14:36:36 INFO - Cleaning up... 14:36:36 INFO - Return code: 0 14:36:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:36 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')]} 14:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:36 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')]} 14:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:36 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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:36 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 14:36:36 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 14:36:36 INFO - Using env: {'DISPLAY': ':0', 14:36:36 INFO - 'HOME': '/home/cltbld', 14:36:36 INFO - 'LANG': 'en_US.UTF-8', 14:36:36 INFO - 'LANGUAGE': 'en_US:en', 14:36:36 INFO - 'LOGNAME': 'cltbld', 14:36:36 INFO - 'MAIL': '/var/mail/cltbld', 14:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:36 INFO - 'MOZ_NO_REMOTE': '1', 14:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:36 INFO - 'NO_EM_RESTART': '1', 14:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:36 INFO - 'PWD': '/builds/slave/test', 14:36:36 INFO - 'SHELL': '/bin/bash', 14:36:36 INFO - 'SHLVL': '1', 14:36:36 INFO - 'TERM': 'linux', 14:36:36 INFO - 'TMOUT': '86400', 14:36:36 INFO - 'USER': 'cltbld', 14:36:36 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:36 INFO - '_': '/tools/buildbot/bin/python'} 14:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:36:36 INFO - Running setup.py (path:/tmp/pip-Y9Q5Ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:36:36 INFO - Running setup.py (path:/tmp/pip-LFyPYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:36:36 INFO - Running setup.py (path:/tmp/pip-A25nPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:36:36 INFO - Running setup.py (path:/tmp/pip-qsGiB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:36:36 INFO - Running setup.py (path:/tmp/pip-7jEQLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:36:36 INFO - Running setup.py (path:/tmp/pip-2fyxVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:36:36 INFO - Running setup.py (path:/tmp/pip-9ereDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:36:36 INFO - Running setup.py (path:/tmp/pip-cuZZ61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:36:36 INFO - Running setup.py (path:/tmp/pip-p6NK7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:36:37 INFO - Running setup.py (path:/tmp/pip-aTuTW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:36:37 INFO - Running setup.py (path:/tmp/pip-eT48DB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:36:37 INFO - Running setup.py (path:/tmp/pip-Y0euhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:36:37 INFO - Running setup.py (path:/tmp/pip-o8e2cP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:36:37 INFO - Running setup.py (path:/tmp/pip-Qt47Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:37 INFO - Running setup.py (path:/tmp/pip-yrYIMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:36:37 INFO - Running setup.py (path:/tmp/pip-I0z0cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:36:37 INFO - Running setup.py (path:/tmp/pip-QhmETE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:36:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:36:37 INFO - Running setup.py install for manifestparser 14:36:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:36:37 INFO - Running setup.py install for mozcrash 14:36:37 INFO - Running setup.py install for mozdebug 14:36:37 INFO - Running setup.py install for mozdevice 14:36:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Running setup.py install for mozfile 14:36:38 INFO - Running setup.py install for mozhttpd 14:36:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Running setup.py install for mozinfo 14:36:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Running setup.py install for mozInstall 14:36:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Running setup.py install for mozleak 14:36:38 INFO - Running setup.py install for mozlog 14:36:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Running setup.py install for moznetwork 14:36:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Running setup.py install for mozprocess 14:36:38 INFO - Running setup.py install for mozprofile 14:36:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:36:38 INFO - Running setup.py install for mozrunner 14:36:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:36:39 INFO - Running setup.py install for mozscreenshot 14:36:39 INFO - Running setup.py install for moztest 14:36:39 INFO - Running setup.py install for mozversion 14:36:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:36:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:36:39 INFO - Cleaning up... 14:36:39 INFO - Return code: 0 14:36:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:39 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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:39 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 14:36:39 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 14:36:39 INFO - Using env: {'DISPLAY': ':0', 14:36:39 INFO - 'HOME': '/home/cltbld', 14:36:39 INFO - 'LANG': 'en_US.UTF-8', 14:36:39 INFO - 'LANGUAGE': 'en_US:en', 14:36:39 INFO - 'LOGNAME': 'cltbld', 14:36:39 INFO - 'MAIL': '/var/mail/cltbld', 14:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:39 INFO - 'MOZ_NO_REMOTE': '1', 14:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:39 INFO - 'NO_EM_RESTART': '1', 14:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:39 INFO - 'PWD': '/builds/slave/test', 14:36:39 INFO - 'SHELL': '/bin/bash', 14:36:39 INFO - 'SHLVL': '1', 14:36:39 INFO - 'TERM': 'linux', 14:36:39 INFO - 'TMOUT': '86400', 14:36:39 INFO - 'USER': 'cltbld', 14:36:39 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:39 INFO - '_': '/tools/buildbot/bin/python'} 14:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:36:39 INFO - Running setup.py (path:/tmp/pip-rVDw6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:36:39 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)) 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:36:39 INFO - Running setup.py (path:/tmp/pip-9rK_Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:36:39 INFO - Running setup.py (path:/tmp/pip-eESdzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:36:39 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)) 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:36:39 INFO - Running setup.py (path:/tmp/pip-kQFR0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:36:39 INFO - Running setup.py (path:/tmp/pip-FPhJL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:36:39 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)) 14:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:36:39 INFO - Running setup.py (path:/tmp/pip-cN9fgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:36:40 INFO - Running setup.py (path:/tmp/pip-3OSCxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:36:40 INFO - Running setup.py (path:/tmp/pip-MyqdsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:36:40 INFO - Running setup.py (path:/tmp/pip-ylpAes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:36:40 INFO - Running setup.py (path:/tmp/pip-BSCVmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:36:40 INFO - Running setup.py (path:/tmp/pip-Ggt3dY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:36:40 INFO - Running setup.py (path:/tmp/pip-jk8jVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:36:40 INFO - Running setup.py (path:/tmp/pip-iaz6x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:36:40 INFO - Running setup.py (path:/tmp/pip-DhIHmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:40 INFO - Running setup.py (path:/tmp/pip-yIILhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:36:40 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:36:40 INFO - Running setup.py (path:/tmp/pip-v5kJxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 14:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:36:40 INFO - Running setup.py (path:/tmp/pip-GDtMw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:36:40 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)) 14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:36:40 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)) 14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:36:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36: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 14:36:42 INFO - Downloading blessings-1.6.tar.gz 14:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:36:42 INFO - Installing collected packages: blessings 14:36:42 INFO - Running setup.py install for blessings 14:36:43 INFO - Successfully installed blessings 14:36:43 INFO - Cleaning up... 14:36:43 INFO - Return code: 0 14:36:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:36:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:36:43 INFO - Reading from file tmpfile_stdout 14:36:43 INFO - Current package versions: 14:36:43 INFO - argparse == 1.2.1 14:36:43 INFO - blessings == 1.6 14:36:43 INFO - blobuploader == 1.2.4 14:36:43 INFO - docopt == 0.6.1 14:36:43 INFO - functools32 == 3.2.3-2 14:36:43 INFO - jsonschema == 2.5.1 14:36:43 INFO - manifestparser == 1.1 14:36:43 INFO - mozInstall == 1.12 14:36:43 INFO - mozcrash == 1.0 14:36:43 INFO - mozdebug == 0.1 14:36:43 INFO - mozdevice == 0.49 14:36:43 INFO - mozfile == 1.2 14:36:43 INFO - mozhttpd == 0.7 14:36:43 INFO - mozinfo == 0.9 14:36:43 INFO - mozleak == 0.1 14:36:43 INFO - mozlog == 3.4 14:36:43 INFO - moznetwork == 0.27 14:36:43 INFO - mozprocess == 0.25 14:36:43 INFO - mozprofile == 0.28 14:36:43 INFO - mozrunner == 6.13 14:36:43 INFO - mozscreenshot == 0.1 14:36:43 INFO - mozsystemmonitor == 0.3 14:36:43 INFO - moztest == 0.8 14:36:43 INFO - mozversion == 1.4 14:36:43 INFO - psutil == 3.1.1 14:36:43 INFO - requests == 1.2.3 14:36:43 INFO - wsgiref == 0.1.2 14:36:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:43 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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:43 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 14:36:43 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 14:36:43 INFO - Using env: {'DISPLAY': ':0', 14:36:43 INFO - 'HOME': '/home/cltbld', 14:36:43 INFO - 'LANG': 'en_US.UTF-8', 14:36:43 INFO - 'LANGUAGE': 'en_US:en', 14:36:43 INFO - 'LOGNAME': 'cltbld', 14:36:43 INFO - 'MAIL': '/var/mail/cltbld', 14:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:43 INFO - 'MOZ_NO_REMOTE': '1', 14:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:43 INFO - 'NO_EM_RESTART': '1', 14:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:43 INFO - 'PWD': '/builds/slave/test', 14:36:43 INFO - 'SHELL': '/bin/bash', 14:36:43 INFO - 'SHLVL': '1', 14:36:43 INFO - 'TERM': 'linux', 14:36:43 INFO - 'TMOUT': '86400', 14:36:43 INFO - 'USER': 'cltbld', 14:36:43 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:43 INFO - '_': '/tools/buildbot/bin/python'} 14:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 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)) 14:36:43 INFO - Cleaning up... 14:36:43 INFO - Return code: 0 14:36:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:36:43 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 0x25311f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2629a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ceb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, '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': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', '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 14:36:43 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 14:36:43 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 14:36:43 INFO - Using env: {'DISPLAY': ':0', 14:36:43 INFO - 'HOME': '/home/cltbld', 14:36:43 INFO - 'LANG': 'en_US.UTF-8', 14:36:43 INFO - 'LANGUAGE': 'en_US:en', 14:36:43 INFO - 'LOGNAME': 'cltbld', 14:36:43 INFO - 'MAIL': '/var/mail/cltbld', 14:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:43 INFO - 'MOZ_NO_REMOTE': '1', 14:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:43 INFO - 'NO_EM_RESTART': '1', 14:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:43 INFO - 'PWD': '/builds/slave/test', 14:36:43 INFO - 'SHELL': '/bin/bash', 14:36:43 INFO - 'SHLVL': '1', 14:36:43 INFO - 'TERM': 'linux', 14:36:43 INFO - 'TMOUT': '86400', 14:36:43 INFO - 'USER': 'cltbld', 14:36:43 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:43 INFO - '_': '/tools/buildbot/bin/python'} 14:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:36:43 INFO - Cleaning up... 14:36:43 INFO - Return code: 0 14:36:43 INFO - Running post-action listener: _resource_record_post_action 14:36:43 INFO - Running post-action listener: _start_resource_monitoring 14:36:43 INFO - Starting resource monitoring. 14:36:43 INFO - [mozharness: 2017-03-15 21:36:43.833989Z] Finished create-virtualenv step (success) 14:36:43 INFO - [mozharness: 2017-03-15 21:36:43.834507Z] Running install step. 14:36:43 INFO - Running pre-action listener: _resource_record_pre_action 14:36:43 INFO - Running main action method: install 14:36:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:36:44 INFO - Reading from file tmpfile_stdout 14:36:44 INFO - Detecting whether we're running mozinstall >=1.0... 14:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:36:44 INFO - Reading from file tmpfile_stdout 14:36:44 INFO - Output received: 14:36:44 INFO - Usage: mozinstall [options] installer 14:36:44 INFO - Options: 14:36:44 INFO - -h, --help show this help message and exit 14:36:44 INFO - -d DEST, --destination=DEST 14:36:44 INFO - Directory to install application into. [default: 14:36:44 INFO - "/builds/slave/test"] 14:36:44 INFO - --app=APP Application being installed. [default: firefox] 14:36:44 INFO - mkdir: /builds/slave/test/build/application 14:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:36:59 INFO - Reading from file tmpfile_stdout 14:36:59 INFO - Output received: 14:36:59 INFO - /builds/slave/test/build/application/firefox/firefox 14:36:59 INFO - Running post-action listener: _resource_record_post_action 14:36:59 INFO - [mozharness: 2017-03-15 21:36:59.585050Z] Finished install step (success) 14:36:59 INFO - [mozharness: 2017-03-15 21:36:59.585196Z] Running run-tests step. 14:36:59 INFO - Running pre-action listener: _resource_record_pre_action 14:36:59 INFO - Running main action method: run_tests 14:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:36:59 INFO - Python 2.7.3 14:36:59 INFO - Return code: 0 14:36:59 INFO - grabbing minidump binary from tooltool 14:36:59 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')]} 14:36:59 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 0x27cc290>, '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 0x27ccb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cd040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:36:59 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 14:36:59 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 14:36:59 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 14:36:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:36:59 INFO - Return code: 0 14:36:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:36:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:36:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:36:59 INFO - ENV: RUST_BACKTRACE is now 1 14:36:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:36:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-080 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:36:59 INFO - Using env: {'DISPLAY': ':0', 14:36:59 INFO - 'HOME': '/home/cltbld', 14:36:59 INFO - 'LANG': 'en_US.UTF-8', 14:36:59 INFO - 'LANGUAGE': 'en_US:en', 14:36:59 INFO - 'LOGNAME': 'cltbld', 14:36:59 INFO - 'MAIL': '/var/mail/cltbld', 14:36:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:36:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:36:59 INFO - 'MOZ_NO_REMOTE': '1', 14:36:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:36:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:36:59 INFO - 'NO_EM_RESTART': '1', 14:36:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:36:59 INFO - 'PWD': '/builds/slave/test', 14:36:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:36:59 INFO - 'RUST_BACKTRACE': '1', 14:36:59 INFO - 'SHELL': '/bin/bash', 14:36:59 INFO - 'SHLVL': '1', 14:36:59 INFO - 'TERM': 'linux', 14:36:59 INFO - 'TMOUT': '86400', 14:36:59 INFO - 'USER': 'cltbld', 14:36:59 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536', 14:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:36:59 INFO - '_': '/tools/buildbot/bin/python'} 14:36:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:36:59 INFO - mozversion application_buildid: 20170315210546 14:36:59 INFO - mozversion application_changeset: 1c4d97d1bc61468c82e55a09bab660e52bb2e9b5 14:36:59 INFO - mozversion application_display_name: Nightly 14:36:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:36:59 INFO - mozversion application_name: Firefox 14:36:59 INFO - mozversion application_remotingname: firefox 14:36:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:36:59 INFO - mozversion application_vendor: Mozilla 14:36:59 INFO - mozversion application_version: 55.0a1 14:36:59 INFO - mozversion platform_buildid: 20170315210546 14:36:59 INFO - mozversion platform_changeset: 1c4d97d1bc61468c82e55a09bab660e52bb2e9b5 14:36:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:36:59 INFO - mozversion platform_version: 55.0a1 14:36:59 INFO - using testdate: 1489613819 14:36:59 INFO - actual date: 1489613819 14:36:59 INFO - starting webserver on 'localhost:58205' 14:36:59 INFO - SUITE-START | Running 2 tests 14:36:59 INFO - TEST-START | dromaeo_css 14:36:59 INFO - operating with platform_type : linux_ 14:36:59 INFO - Initialising browser for dromaeo_css test... 14:36:59 INFO - TEST-INFO | started process 23691 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpobOZ9i/profile http://localhost:58205/getInfo.html) 14:37:05 INFO - PID 23691 | __metrics Screen width/height:1600/1200 14:37:05 INFO - PID 23691 | colorDepth:24 14:37:05 INFO - PID 23691 | Browser inner width/height: 1024/697 14:37:05 INFO - PID 23691 | __metrics 14:37:06 ERROR - PID 23691 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 14:37:07 INFO - TEST-INFO | 23691: exit 0 14:37:07 INFO - Browser initialized. 14:37:07 INFO - Running cycle 1/1 for dromaeo_css test... 14:37:07 INFO - TEST-INFO | started process 23823 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpobOZ9i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:37:19 INFO - PID 23823 | RSS: Main: 157974528 14:37:19 INFO - PID 23823 | 14:40:23 INFO - PID 23823 | Cycle 1(1): loaded http://localhost:58205/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58205/tests/dromaeo/cssquery-ext.html) 14:40:25 INFO - PID 23823 | RSS: Main: 166117376 14:40:25 INFO - PID 23823 | 14:42:07 INFO - PID 23823 | ************************* 14:42:07 INFO - PID 23823 | A coding exception was thrown and uncaught in a Task. 14:42:07 INFO - PID 23823 | 14:42:07 ERROR - PID 23823 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:42:07 INFO - PID 23823 | Full stack: 14:42:07 INFO - PID 23823 | ************************* 14:43:28 INFO - PID 23823 | Cycle 1(1): loaded http://localhost:58205/tests/dromaeo/cssquery-ext.html (next: http://localhost:58205/tests/dromaeo/cssquery-jquery.html) 14:43:28 INFO - PID 23823 | RSS: Main: 161320960 14:43:28 INFO - PID 23823 | 14:46:30 INFO - PID 23823 | Cycle 1(1): loaded http://localhost:58205/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58205/tests/dromaeo/cssquery-mootools.html) 14:46:31 INFO - PID 23823 | RSS: Main: 169254912 14:46:31 INFO - PID 23823 | 14:49:33 INFO - PID 23823 | Cycle 1(1): loaded http://localhost:58205/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58205/tests/dromaeo/cssquery-prototype.html) 14:49:34 INFO - PID 23823 | RSS: Main: 161394688 14:49:34 INFO - PID 23823 | 14:52:37 INFO - PID 23823 | Cycle 1(1): loaded http://localhost:58205/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58205/tests/dromaeo/cssquery-yui.html) 14:52:37 INFO - PID 23823 | RSS: Main: 168554496 14:52:37 INFO - PID 23823 | 14:55:40 INFO - PID 23823 | Cycle 1(1): loaded http://localhost:58205/tests/dromaeo/cssquery-yui.html (next: http://localhost:58205/tests/dromaeo/cssquery-dojo.html) 14:55:41 INFO - PID 23823 | RSS: Main: 161525760 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | __start_tp_report 14:55:41 INFO - PID 23823 | _x_x_mozilla_page_load 14:55:41 INFO - PID 23823 | _x_x_mozilla_page_load_details 14:55:41 INFO - PID 23823 | |i|pagename|runs| 14:55:41 INFO - PID 23823 | |0;dojo.html;2673.6390587270716;2683.2084535062154;2687.0460986349844;2715.9456810863794;2724.5232084385234;4933.7779799909;4938.950610493894;4991.326170966923;5019.824306149286;5068.062408454434;4989.950100498994;4999;5011.473795251501;5014.623903207265;5101.693898366091;4552.90894182117;4557.339185818055;4599.4710608279975;4602.930956035662;4630.652701047435;4606.032733126048;4651.348811166439;4658.650601204923;4666.136764698542;4666.160091183586;4499.505054444008;4517.638588912912;4563;4568.680192386534;4630.513796051399;4566.4520257568975;4585.082983403333;4589.747563883985;4623.653226008063;4629.930551041737;4534.614557762561;4540.954590454086;4547.658925580562;4566.292224705143;4581.106684196583;4896.706197628154;4927.1870141426625;4943.604511639096;4999.150144475448;5002.9499705002845;5380.273663055476;5389.461053894618;5407.83776486706;5429.104197807357;5456.699881506515;5348.903474787659;5358.705271210082;5364.678119312854;5365.08793505105;5379.273798037254;4887.437944636341;4973.52751488608;5008.449070602231;5009.549140577293;5071.2139618359215;5334.0665383557725;5348.411674715778;5371.435999220025;5387;5426.9185962210595;5172.379314482211;5243.056249875059;5246.108161612458;5248.029114613809;5252.080885844962;5251.737413129328;5296.947030529723;5325.86685332862;5334.466553344634;5344.198370244494;5202.531772140525;5221.216817477409;5275.076861549213;5277.393099793496;5280.155175171954;5248.029114613809;5264.842054738364;5280.7359632019015;5334.786608535693;5343.973280133652;4512.120136573402;4526.637868970474;4529.297958816389;4534.546545345505;4535.9546404536195;3927.430522574268;3941.881743547698;3949.3681011038097;3949.782761948063;3960.663343615767;4527.139843429738;4600.769961501912;4605.424321959755;4607.723536587815;4641.280601506772;5337.919931201035;5340.092184328596;5365.195220716923;5368.409474957752;5376.8118115865755;4582.9770851146195;4594.218982772936;4595.770211489412;4596.655250856199;4600.700954437951;4771.260454629538;4771.308160316735;4777.355057067251;4780.737059461763;4797.376341075638;5042.974785126051;5053.166227572483;5060.822871199491;5062.696238225718;5064.670796398223;5326.866828329323;5362.410134885237;5387.111126664056;5431.83704488866;5432.103702888979;5046.016026874871;5047.2681461187185;5048.949510504848;5093.1341671915125;5097.439281679087;5271.156614941591;5309.65487243328;5310.9468905312;5320.521153096085;5365.248865158959;5259.973700131475;5332.33345831771;5364.597655175956;5406.135018397038;5407.891842163056;5218.269442278008;5230;5255.710935898563;5295.099833028317;5307.363116426053;5184.948150518447;5192.532672059533;5195.272661827423;5211.218317252443;5233.528982391603;5057.69653820772;5106.336176297058;5116.74416279192;5118.206677964921;5164.406093299169;5133.820316288912;5134.640575159852;5148.459411761861;5161.896762064813;5168.302279192261;5023.321851549994;5028.044671512401;5028.069807085701;5029.4719054498755;5033.094043072282 14:55:41 INFO - PID 23823 | |1;ext.html;615.5598746895971;677.4546490075488;706.6466766616693;845.6701886264358;858.527809704663;34769.30461390774;35469.2906141877;35486.290274194536;35488.64511354889;35576;31512.369752604933;31970.36059278813;32065.19837004076;32073;32113.518297225502;6216.84457888553;8610.956945215265;8793.648254069829;8811.911880881173;8827.514486703229;9150.084991500864;9226.261899048093;9258.444493330388;9281.58232879522;9284.210842078432;9230.538473076353;9288.907110928905;9327.253819694442;9356.111169438926;9356.391834530734;9170.862437063415;9257.86113208299;9308;9312.34813563051;9325.580348884316;8977.326700497424;9012.8648070279;9042.050584688644;9065.365424420293;9069.047749986288;34754.30491390158;34756.47865282023;34782.65217347819;34804.82597587021;34883.127921801904;3322.6843449872467;3388.203772113551;3394.6775056369606;3434.227298857762;3450.189205536697;24591.262262132164;24694.431530166978;24825.62761558578;25520.36199095019;25558.10546630859;3021.5165573508352;3109.8289594072317;3129.749620030392;3221.629512606057;3224.8548815303366;111162;112094.43952780265;139993;155585;155680;41329.58670413317;44648.776756116014;44972.775136124765;45021;45208.321875171896;46572.301415478796;46638;46830;46848.29727554089;46868.296975545396;12979.35103244834;12983.870161298455;13067.150635208682;13073;13140.671483212996;10328.896711032943;10361.740956475998;10380.42907640072;10424.426656533811;10509.789804204025;13674.93162534181;13691;13701.588952331445;13857.099288546213;13930.795916939162;5677.63095398806;5703.800366987136;5713.171590119409;5739.67543145027;5826.621269617462;938.4181807279531;981.087588542648;982.1995074014656;993.1359717046216;996.566493575297;24126.758732412804;24214.87892560526;24406.389840254134;24437.38906527351;24449.266522004367;1026.810040142576;1032.0608246495653;1040.167865707431;1064.893510648929;1065.0627447846;12487.75024499505;12888.93555532235;12944.09391342597;12967.416466259041;13002.674933126558;208248;212240;213656;214364;231667;45464.77267613707;45979;46047.539524604996;46062.07875842464;46297.768511157905;1197.7484728207;1226.5277868020876;1226.6381417481864;1250.0874361715817;1261.9810702839466;24417.26748197557;24592.631110533;25667.743322566534;25708.871455642602;25811;36754.448683270304;37003.25993480169;37161.256774864894;37186.44220336643;37195.442068369535;235462;237282;238038;238056;238899;22071.227507037176;22380;24784;25231.87384063068;29022.709772902002;32293.192670183435;32298.192545186557;32359.352812944082;32566.348673025932;32810.17974550655;27777.861110694317;27799.44401111926;27801.58297625497;27853.442931141668;28388.716112838607;27572;27686.723132768413;28163;28493;28704.856475717486;27888.44223115567;28138.577921331605;28173.154805355873;28299.15102546927;30657.54013689841;22994.310170694906;25034.74965250397;26183.345416364744;26233.344166395993;29426.852865735535;23389.649155262345;23489.76510234944;23509.52980940406;23834.761652383255;23936.162234321717 14:55:41 INFO - PID 23823 | |2;jquery.html;381.50975995845346;398.5297349128028;411.1817483208415;413.11387074724695;433.18993482188307;12583.874161258385;17576.73634895476;18458.72311915321;18473.907630461847;18606.627867442643;17211.82788172118;17364.479065628053;17509.91245043776;17517.649647007052;17628.735568966476;5314.495122963332;5558.110702287635;5595.580331475136;6710.16122984627;6976.127984002;6784.253732089466;6819.829504262383;6836;6862.348076932683;6862.7941161764975;6792.796216113525;6857.759978400732;6859.07402500661;7009.404200642926;7019.719211231544;6888.896666550006;6916.96245563162;6954.095967524189;7047.788566343018;7070.540414873017;6776.864462710718;6830.248672646005;6861.245263021114;6863.553868998499;6879.071325371111;21244.681329780065;21763.041326280712;22213;22415.887920560457;22496.88751556248;1999.9700004499944;2493.8628375439343;2572.266903932389;2573.266619013591;2580.87095645217;13642.795358069638;13910.791338129937;14006;14940.32768525419;15083.47207847731;1557.4315374888083;1640.3274657390414;1660.0786563457277;1664.716998110324;1693.2191970831852;84079.57960210221;90924.54537727269;93975.53012234895;94097.529512352;95038.52480737552;16056.839431605535;16539.91730041366;16691.24889379981;17190.50737643035;17880.374186903395;25908.740912590634;28454.857725711237;28571.28571785722;28645.427091458056;28931.42137157245;8708.390412671179;8733.170348816851;8847.911520884838;8861.290677110826;8990.415622984485;7522.247775222434;7571.621418929031;7737.574433406104;7751.379889608817;7764.417668674759;8672.696455624024;8795.912040879637;9033.864492032624;9108.49903255314;9209.63161473547;3656.8902932912056;3759.793211373346;3763.6989040876665;3814.9427758583643;3837.1174629834736;810.2869474862084;829.87966245568;836.2264904963007;837.5226121110908;842.5408152556871;18177.72733409;19119.13963871629;19239.326623568108;19258.518537036685;19472.123754431086;821.1870248453988;826.8553003224412;839.3914412051214;846.4582667092942;850.6724910909221;9720.805583888425;10675.199360048027;10757.218122242553;10785.687061630277;10792.47823977333;209800;210398;210525;210935;245826;86154;89085;90474.54762726276;90798;94342.52828735813;1122.966311010671;1140.8231724082614;1146.2377518949804;1146.896779289868;1151.2804497189256;15637.168893620681;16086.43697470583;16166.434174803826;16675.74986375181;16755.497335079966;38406;42134.36798448087;43081.353779692705;43769.78115109404;44849.77575112104;50781.23828142507;51565.742171288905;51895.22157167721;52101.21848172205;52464.47535524596;7745.380369570534;9981.600735970771;10085.142762864945;10139.7465063374;10209.846852297072;11736.119791015537;11846.822297665714;12001.339926304137;12069.456874440495;12356.62930112098;10127.898721012696;10300.433476158583;10319.742006449898;10475.476226188812;10721.195910306915;9797.167240784329;9830.213582913208;9853.261005424765;9914.454704991007;10286.537105830106;9840.261980351646;9898.703038908845;9983.900160998588;10106.39361638304;10342.172626189737;9368.266870628955;9817.85273220888;10020.590939986383;10141.797164056825;10485.16118710486;11358.886411135783;11362.318260904372;11380.886191138314;11479.139064570358;11883.108766842348 14:55:41 INFO - PID 23823 | |3;mootools.html;1449.8840092792577;1476.3356489579685;1483.9035462694917;1514.2958524286207;1563.7967064281643;8169.91830081699;8480.32157427406;8803.559822008898;9132.086791320873;9284.118008789163;8588.656453741844;8817.647294108228;8881.91118088819;8895.599698013588;8902.376833621633;2009.9598008039868;2046.1610739596695;2051.1590247998324;2052.866563673364;2053.045333919727;1968.7932767059467;2005.8595898287126;2013.1745984146503;2017.5662232619984;2021.675802349456;1985.5929534445404;1992.3823614679495;2001.0795034284174;2001.279539365827;2001.8098280663387;1934.7001214811796;1937.9709304360447;1941.7184508246296;1943.232423192845;1948.7174359717833;1875.3904980881268;1875.7749070111674;1881.416760804155;2023.7672667643262;2042.4893776555862;9417;9418.10527999839;9498.857517137249;9510.857337139949;9523.285753568442;4520.50274469808;4687.507811679793;4700.224462963607;4704.45898721648;4711.835085772016;2551.329853343276;2625.1599488163797;2639.4061336199393;2661.2681512584;2726.359305563191;961.711486554031;974.4543055888654;1025.0825511167463;1030.5414090729628;1035.9326643768131;13308;13387.196768193939;13422.798658020138;13445.731085378238;13446.12600180995;3744.812759362021;3850.537935447764;3957.9010524736536;3973.165635216637;4008.6392224699916;11588.188826782212;11650;11656.592019279284;11683.474243658886;11698.88301116995;4568.246239370533;4614.238650622677;4631.930521042187;4639.234526303122;4669;3619.2037751694584;3688.967089215024;4173.603507666767;4219.746815191098;4244.384564238168;3356.47974563943;3366.713829324535;3536.8054756988613;3536.840842162109;3580.695640870555;926.8192702422963;949.715085474355;959.5346257065318;966.1546147121269;970.5001924009141;843.4180415513276;847.9957600211959;856.9957150214209;879.2087121590491;880.6521424037597;5695.060315047971;5757.251557297608;5812.796552120655;5916.5742045761035;5923.881522369615;904.6607522179182;907.120093509295;913.2876356441986;913.881195444588;916.8716379706847;3622.3298689742483;3640.543782486973;3672;3707.6663100321102;3760.4735337052834;10826.566937322576;10888.564457421773;11234.76417594064;11461.083113350913;11463.541458341573;11213;11243.044341230923;11259.774804503864;11277.887221127683;11289;8137.186281371815;8463.280621147149;8586.356023298278;8587.441816281796;8603.956980215058;6028.939710602838;6034.306054803578;6097.932861749087;6128.325884152829;6259.812205633838;9632.518374081128;10775.838362424727;10806.243562950518;10874.836877447004;10973.341599504056;10079.143272821892;10130.645427410005;10187.439690817077;10199.898001019896;10214;7595.658195381111;7726.227377262454;7769.53382797034;8555.401121921655;8778.780530486789;8688.30493560501;8945.910540894769;8946.507942062988;8970.013298537026;8973.37186396946;7749.535027898344;7789.844203116019;7938.126806051445;8162.591870406574;8310.83378332442;7557.244275572619;7598.962005189938;7623.428242881917;7654.655540500579;7925.207479252029;6871;6991.056207412138;7098.112735908012;7100.502964792415;7120.608366539683;5034.471380504994;5114.693118412907;5148.30497882781;5163.66436181647;5255.448177941414;5076.543111120017;5187.19598462239;5199.246109314204;5216.2436446714255;5384.7576859040655 14:55:41 INFO - PID 23823 | |4;prototype.html;353.06790074204105;354.9059499232702;355.1671330729442;367.7444176297474;374.1133513572832;5168.5606723428555;5333.679979201251;5388.568914486837;5398.81104161355;5427.1316589345715;5110.6422550421485;5208.76560554775;5276.3668359796875;5286.154215325549;5300.602454815885;4524.932126018112;4601.7469039203;4607.239805432117;4612.607928326096;4613.077384523092;4499.627613577857;4548.5224051474615;4569.4516658001085;4605.078984203156;4646.186917289478;4540.432445944257;4564.954350456486;4584.19776539106;4591.380163677911;4601.079784043188;4569;4577.908441831145;4578.931316030245;4582.862514124582;4583.106294272585;4316.395704601358;4395.164918665475;4425.358323043173;4431.9778401108115;4461.397711308964;5158.871028224287;5170.767315470813;5177.715225662587;5216.608754343401;5233.528982391565;5337.572994160458;5346.198070289449;5373.76403427214;5375.650582712111;5421.701806400646;5473.01485732568;5653.943460565424;5662.801801936913;5664.6601203927485;5678.119891416837;4863.489333619955;4869.756512174377;4873.34209881665;4891.388576427947;4902.877428064291;5639.379668236491;5683.488486036235;5804.854878628026;5818.854528636818;5888.322842873082;4981.3026176334615;5070.61970352225;5071.771770270347;5086.3133476980875;5095.566876815438;5136.8202112925865;5180.4560521145495;5189.4711596115;5197;5203.557697595671;5118;5339.786408543692;5366.704831234241;5379.220013098079;5429.1856221566295;5679.176519404663;5685.175649530877;5686.14707793826;5687.544996400278;5689.715514224272;1758.2439550993195;1826.5433641589602;1860.981390186108;1865.1420346640425;1870.4949663590755;1446;1472.9189894555902;1489.6127006978263;1519.5061604978425;1522.6726253855475;4783.880402989953;4815.7351345676325;4825.4933232010835;4860.854174374775;4861.975690121527;5683.715814209273;5691.77232910679;5694.914576281359;5708.914366284509;5736.368999410061;4671.6262698984;4828;4858.975705121452;4876.926846097312;4887.193613053877;3060.7551395888277;3083.290843106053;3089.8300593467575;3098.10155055036;3099.7210251077513;5373;5378.78484860609;5383.273258110105;5385.75764090609;5403.567714582824;5515.889682206413;5926.111083337534;5936.376680448577;5992.131140984533;5999.520038397005;5442.4829641184815;5444.945550544444;5447.128459446549;5462.6995515247045;5483.8080667176455;5722.798212375448;5847.210626565359;5873.471387575139;5875.094296262669;5896.64620122794;5588.608797384144;5640.125780504029;5677.8864422710485;5737.512311453406;5756.30924289088;5401.351837779492;5405.324334458192;5439.428859969805;5447.1829225616475;5453.918191227055;4600.194965880891;4635.745034023161;4636.03943780152;4666.8133274668635;4671.486136525047;5709.8287051388525;5781.248437703071;5825.0679891217205;5840.2991641003355;5862.179294898632;5637.210790489253;5643.463870932255;5644.294463192101;5657.4908258256955;5664.320281566238;5466.3713672928125;5630.85922851932;5637.46444087811;5641.689707066151;5671.744771485375;5538.418466061005;5572.164175373727;5576.10782274834;5585.441455854382;5615.241942337732;5701.657900525982;5705.201271822031;5710.5146062585145;5727.198192253171;5744.678723893611;5383.353997520323;5528.281323427928;5528.308961379827;5572.526335261548;5586.553075754011 14:55:41 INFO - PID 23823 | |5;yui.html;655.6754406568749;678.3521736741412;724.3263764698828;736.7789663101069;744.3301029073833;12410.689732756675;13130.999999999987;13148.041690619617;13192.208467491933;13194.736105277889;12039.398030098482;12115.819152552536;12186.573469928555;12190.207636503621;12203.328816915066;812.528733334666;825.793551612097;853.9103394143618;857.8756080368343;858.51064893011;854.3037424499014;854.4403415762686;854.5684429363163;856.1267507142711;857.5626430520417;891.9598618062203;892.857142857143;893.2452077994085;893.852514335134;894.838928992781;881.0924747510074;883.4875772052194;889.2485849457263;889.6975028490313;894.4633220067972;651.775137577535;655.6000839487908;655.6983787457775;656.7635651165576;658.1871388834785;13387.665308367272;13499.797503037462;13567.118137321142;13862.168269903737;14748.410063597443;13589.864101359057;14409.71180576393;14438.92780536101;14484.92757536216;14500.41998320066;10315.948420257926;10368.289232276666;10385.948070259676;10448.582056717722;10520;3150.921226969321;3156.384505021522;3192.409404260219;3201.6478187399366;3318.0875259303884;63840;65034.67482662604;65662.3433765661;66065.66967165134;66351.33648663548;10342.327748696462;10354;10470.633527826489;10508.737281567868;10538.525766340534;21074.78925210759;21357.78642213558;21526.56946861054;21630.56738865214;21642.134314627237;6630.635315057718;6668.099806526154;6702.329767023259;6704.564203326768;6962.860742785116;3404.914877128042;3407.676270754324;3446.7414943879307;3486.651334866493;3520.612732599412;2793.189974907254;2797.59434881941;2827.5899994500683;2842.2325971987448;2868;446.1990726645679;448.3879504476366;454.5590776946357;455.0603004795088;461.6606794006403;649.8472858878159;654.5295630841437;668.5859407353413;673.737242475435;688.3082502085372;8633.352498562634;8806.339524535557;9076.773080673036;9478.857817132748;9703.951480242553;581.8283606336141;616.4444753273314;626.683524819963;626.9749210031639;628.7830698409042;4070.6539944105084;4144.772037537904;4214.1571685662375;4229.513605935295;4367.126574685076;49557.50442495601;60608;60736.69631651814;61729;62189.378106218355;47223.291650625266;53552;54907.72546137324;54917.72541137269;55898.44101558932;47394;48385;49591.75204124028;50231;50720.74639626852;7851.803704907423;8161.306288965505;8168;8185.427020108774;8279.875801863098;33640;33906.83046584751;34870.825645871606;35038;35195.82402087973;39458;40762.79618602007;40846;41620.167596648505;41809.581904180566;9334.766630834283;9630.374025688589;9685.225181985283;9686.564104615169;9757.114355762444;10927.344359338465;11053.17101217428;11156.721081972692;11208.55165793359;11505;9833.60665573369;10066.899331006596;10260.897391025994;10284.640037598649;10581.629642962767;10946.39794811293;11080.501377438195;11435.313881167156;11657.242279251821;11666.650000499998;9449.811003780023;9454.338196326466;9672.274579406432;9681.612735490502;9828.11546960777;8756.737297881074;8936.553172341228;9106.681266155652;9187.173154416134;9204.723858284262;8078.798030049295;8282.213189746964;8779.604917778843;8905.599248033983;8958.193762561401 14:55:41 INFO - PID 23823 | __end_tp_report 14:55:41 INFO - PID 23823 | __start_cc_report 14:55:41 INFO - PID 23823 | _x_x_mozilla_cycle_collect,376 14:55:41 INFO - PID 23823 | __end_cc_report 14:55:41 INFO - PID 23823 | __startTimestamp1489614941012__endTimestamp 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | ------- Summary: start ------- 14:55:41 INFO - PID 23823 | Number of tests: 6 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | [#0] dojo.html Cycles:165 Average:4926.61 Median:5057.70 stddev:527.29 (10.4%) stddev-sans-first:498.41 14:55:41 INFO - PID 23823 | Values: 2673.6 2683.2 2687.0 2715.9 2724.5 4933.8 4939.0 4991.3 5019.8 5068.1 4990.0 4999.0 5011.5 5014.6 5101.7 4552.9 4557.3 4599.5 4602.9 4630.7 4606.0 4651.3 4658.7 4666.1 4666.2 4499.5 4517.6 4563.0 4568.7 4630.5 4566.5 4585.1 4589.7 4623.7 4629.9 4534.6 4541.0 4547.7 4566.3 4581.1 4896.7 4927.2 4943.6 4999.2 5002.9 5380.3 5389.5 5407.8 5429.1 5456.7 5348.9 5358.7 5364.7 5365.1 5379.3 4887.4 4973.5 5008.4 5009.5 5071.2 5334.1 5348.4 5371.4 5387.0 5426.9 5172.4 5243.1 5246.1 5248.0 5252.1 5251.7 5296.9 5325.9 5334.5 5344.2 5202.5 5221.2 5275.1 5277.4 5280.2 5248.0 5264.8 5280.7 5334.8 5344.0 4512.1 4526.6 4529.3 4534.5 4536.0 3927.4 3941.9 3949.4 3949.8 3960.7 4527.1 4600.8 4605.4 4607.7 4641.3 5337.9 5340.1 5365.2 5368.4 5376.8 4583.0 4594.2 4595.8 4596.7 4600.7 4771.3 4771.3 4777.4 4780.7 4797.4 5043.0 5053.2 5060.8 5062.7 5064.7 5326.9 5362.4 5387.1 5431.8 5432.1 5046.0 5047.3 5048.9 5093.1 5097.4 5271.2 5309.7 5310.9 5320.5 5365.2 5260.0 5332.3 5364.6 5406.1 5407.9 5218.3 5230.0 5255.7 5295.1 5307.4 5184.9 5192.5 5195.3 5211.2 5233.5 5057.7 5106.3 5116.7 5118.2 5164.4 5133.8 5134.6 5148.5 5161.9 5168.3 5023.3 5028.0 5028.1 5029.5 5033.1 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | [#1] ext.html Cycles:180 Average:34503.30 Median:16883.68 stddev:52393.04 (310.3%) stddev-sans-first:52478.23 14:55:41 INFO - PID 23823 | Values: 615.6 677.5 706.6 845.7 858.5 34769.3 35469.3 35486.3 35488.6 35576.0 31512.4 31970.4 32065.2 32073.0 32113.5 6216.8 8611.0 8793.6 8811.9 8827.5 9150.1 9226.3 9258.4 9281.6 9284.2 9230.5 9288.9 9327.3 9356.1 9356.4 9170.9 9257.9 9308.0 9312.3 9325.6 8977.3 9012.9 9042.1 9065.4 9069.0 34754.3 34756.5 34782.7 34804.8 34883.1 3322.7 3388.2 3394.7 3434.2 3450.2 24591.3 24694.4 24825.6 25520.4 25558.1 3021.5 3109.8 3129.7 3221.6 3224.9 111162.0 112094.4 139993.0 155585.0 155680.0 41329.6 44648.8 44972.8 45021.0 45208.3 46572.3 46638.0 46830.0 46848.3 46868.3 12979.4 12983.9 13067.2 13073.0 13140.7 10328.9 10361.7 10380.4 10424.4 10509.8 13674.9 13691.0 13701.6 13857.1 13930.8 5677.6 5703.8 5713.2 5739.7 5826.6 938.4 981.1 982.2 993.1 996.6 24126.8 24214.9 24406.4 24437.4 24449.3 1026.8 1032.1 1040.2 1064.9 1065.1 12487.8 12888.9 12944.1 12967.4 13002.7 208248.0 212240.0 213656.0 214364.0 231667.0 45464.8 45979.0 46047.5 46062.1 46297.8 1197.7 1226.5 1226.6 1250.1 1262.0 24417.3 24592.6 25667.7 25708.9 25811.0 36754.4 37003.3 37161.3 37186.4 37195.4 235462.0 237282.0 238038.0 238056.0 238899.0 22071.2 22380.0 24784.0 25231.9 29022.7 32293.2 32298.2 32359.4 32566.3 32810.2 27777.9 27799.4 27801.6 27853.4 28388.7 27572.0 27686.7 28163.0 28493.0 28704.9 27888.4 28138.6 28173.2 28299.2 30657.5 22994.3 25034.7 26183.3 26233.3 29426.9 23389.6 23489.8 23509.5 23834.8 23936.2 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | [#2] jquery.html Cycles:180 Average:22190.12 Median:2072.60 stddev:39256.46 (1894.1%) stddev-sans-first:39332.43 14:55:41 INFO - PID 23823 | Values: 381.5 398.5 411.2 413.1 433.2 12583.9 17576.7 18458.7 18473.9 18606.6 17211.8 17364.5 17509.9 17517.6 17628.7 5314.5 5558.1 5595.6 6710.2 6976.1 6784.3 6819.8 6836.0 6862.3 6862.8 6792.8 6857.8 6859.1 7009.4 7019.7 6888.9 6917.0 6954.1 7047.8 7070.5 6776.9 6830.2 6861.2 6863.6 6879.1 21244.7 21763.0 22213.0 22415.9 22496.9 2000.0 2493.9 2572.3 2573.3 2580.9 13642.8 13910.8 14006.0 14940.3 15083.5 1557.4 1640.3 1660.1 1664.7 1693.2 84079.6 90924.5 93975.5 94097.5 95038.5 16056.8 16539.9 16691.2 17190.5 17880.4 25908.7 28454.9 28571.3 28645.4 28931.4 8708.4 8733.2 8847.9 8861.3 8990.4 7522.2 7571.6 7737.6 7751.4 7764.4 8672.7 8795.9 9033.9 9108.5 9209.6 3656.9 3759.8 3763.7 3814.9 3837.1 810.3 829.9 836.2 837.5 842.5 18177.7 19119.1 19239.3 19258.5 19472.1 821.2 826.9 839.4 846.5 850.7 9720.8 10675.2 10757.2 10785.7 10792.5 209800.0 210398.0 210525.0 210935.0 245826.0 86154.0 89085.0 90474.5 90798.0 94342.5 1123.0 1140.8 1146.2 1146.9 1151.3 15637.2 16086.4 16166.4 16675.7 16755.5 38406.0 42134.4 43081.4 43769.8 44849.8 50781.2 51565.7 51895.2 52101.2 52464.5 7745.4 9981.6 10085.1 10139.7 10209.8 11736.1 11846.8 12001.3 12069.5 12356.6 10127.9 10300.4 10319.7 10475.5 10721.2 9797.2 9830.2 9853.3 9914.5 10286.5 9840.3 9898.7 9983.9 10106.4 10342.2 9368.3 9817.9 10020.6 10141.8 10485.2 11358.9 11362.3 11380.9 11479.1 11883.1 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | [#3] mootools.html Cycles:180 Average:5775.50 Median:4635.58 stddev:3636.90 (78.5%) stddev-sans-first:3632.58 14:55:41 INFO - PID 23823 | Values: 1449.9 1476.3 1483.9 1514.3 1563.8 8169.9 8480.3 8803.6 9132.1 9284.1 8588.7 8817.6 8881.9 8895.6 8902.4 2010.0 2046.2 2051.2 2052.9 2053.0 1968.8 2005.9 2013.2 2017.6 2021.7 1985.6 1992.4 2001.1 2001.3 2001.8 1934.7 1938.0 1941.7 1943.2 1948.7 1875.4 1875.8 1881.4 2023.8 2042.5 9417.0 9418.1 9498.9 9510.9 9523.3 4520.5 4687.5 4700.2 4704.5 4711.8 2551.3 2625.2 2639.4 2661.3 2726.4 961.7 974.5 1025.1 1030.5 1035.9 13308.0 13387.2 13422.8 13445.7 13446.1 3744.8 3850.5 3957.9 3973.2 4008.6 11588.2 11650.0 11656.6 11683.5 11698.9 4568.2 4614.2 4631.9 4639.2 4669.0 3619.2 3689.0 4173.6 4219.7 4244.4 3356.5 3366.7 3536.8 3536.8 3580.7 926.8 949.7 959.5 966.2 970.5 843.4 848.0 857.0 879.2 880.7 5695.1 5757.3 5812.8 5916.6 5923.9 904.7 907.1 913.3 913.9 916.9 3622.3 3640.5 3672.0 3707.7 3760.5 10826.6 10888.6 11234.8 11461.1 11463.5 11213.0 11243.0 11259.8 11277.9 11289.0 8137.2 8463.3 8586.4 8587.4 8604.0 6028.9 6034.3 6097.9 6128.3 6259.8 9632.5 10775.8 10806.2 10874.8 10973.3 10079.1 10130.6 10187.4 10199.9 10214.0 7595.7 7726.2 7769.5 8555.4 8778.8 8688.3 8945.9 8946.5 8970.0 8973.4 7749.5 7789.8 7938.1 8162.6 8310.8 7557.2 7599.0 7623.4 7654.7 7925.2 6871.0 6991.1 7098.1 7100.5 7120.6 5034.5 5114.7 5148.3 5163.7 5255.4 5076.5 5187.2 5199.2 5216.2 5384.8 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | [#4] prototype.html Cycles:180 Average:4880.99 Median:5356.45 stddev:1249.03 (23.3%) stddev-sans-first:1205.42 14:55:41 INFO - PID 23823 | Values: 353.1 354.9 355.2 367.7 374.1 5168.6 5333.7 5388.6 5398.8 5427.1 5110.6 5208.8 5276.4 5286.2 5300.6 4524.9 4601.7 4607.2 4612.6 4613.1 4499.6 4548.5 4569.5 4605.1 4646.2 4540.4 4565.0 4584.2 4591.4 4601.1 4569.0 4577.9 4578.9 4582.9 4583.1 4316.4 4395.2 4425.4 4432.0 4461.4 5158.9 5170.8 5177.7 5216.6 5233.5 5337.6 5346.2 5373.8 5375.7 5421.7 5473.0 5653.9 5662.8 5664.7 5678.1 4863.5 4869.8 4873.3 4891.4 4902.9 5639.4 5683.5 5804.9 5818.9 5888.3 4981.3 5070.6 5071.8 5086.3 5095.6 5136.8 5180.5 5189.5 5197.0 5203.6 5118.0 5339.8 5366.7 5379.2 5429.2 5679.2 5685.2 5686.1 5687.5 5689.7 1758.2 1826.5 1861.0 1865.1 1870.5 1446.0 1472.9 1489.6 1519.5 1522.7 4783.9 4815.7 4825.5 4860.9 4862.0 5683.7 5691.8 5694.9 5708.9 5736.4 4671.6 4828.0 4859.0 4876.9 4887.2 3060.8 3083.3 3089.8 3098.1 3099.7 5373.0 5378.8 5383.3 5385.8 5403.6 5515.9 5926.1 5936.4 5992.1 5999.5 5442.5 5444.9 5447.1 5462.7 5483.8 5722.8 5847.2 5873.5 5875.1 5896.6 5588.6 5640.1 5677.9 5737.5 5756.3 5401.4 5405.3 5439.4 5447.2 5453.9 4600.2 4635.7 4636.0 4666.8 4671.5 5709.8 5781.2 5825.1 5840.3 5862.2 5637.2 5643.5 5644.3 5657.5 5664.3 5466.4 5630.9 5637.5 5641.7 5671.7 5538.4 5572.2 5576.1 5585.4 5615.2 5701.7 5705.2 5710.5 5727.2 5744.7 5383.4 5528.3 5528.3 5572.5 5586.6 14:55:41 INFO - PID 23823 | 14:55:41 INFO - PID 23823 | [#5] yui.html Cycles:180 Average:14216.20 Median:52136.37 stddev:17514.84 (33.6%) stddev-sans-first:17534.37 14:55:41 INFO - PID 23823 | Values: 655.7 678.4 724.3 736.8 744.3 12410.7 13131.0 13148.0 13192.2 13194.7 12039.4 12115.8 12186.6 12190.2 12203.3 812.5 825.8 853.9 857.9 858.5 854.3 854.4 854.6 856.1 857.6 892.0 892.9 893.2 893.9 894.8 881.1 883.5 889.2 889.7 894.5 651.8 655.6 655.7 656.8 658.2 13387.7 13499.8 13567.1 13862.2 14748.4 13589.9 14409.7 14438.9 14484.9 14500.4 10315.9 10368.3 10385.9 10448.6 10520.0 3150.9 3156.4 3192.4 3201.6 3318.1 63840.0 65034.7 65662.3 66065.7 66351.3 10342.3 10354.0 10470.6 10508.7 10538.5 21074.8 21357.8 21526.6 21630.6 21642.1 6630.6 6668.1 6702.3 6704.6 6962.9 3404.9 3407.7 3446.7 3486.7 3520.6 2793.2 2797.6 2827.6 2842.2 2868.0 446.2 448.4 454.6 455.1 461.7 649.8 654.5 668.6 673.7 688.3 8633.4 8806.3 9076.8 9478.9 9704.0 581.8 616.4 626.7 627.0 628.8 4070.7 4144.8 4214.2 4229.5 4367.1 49557.5 60608.0 60736.7 61729.0 62189.4 47223.3 53552.0 54907.7 54917.7 55898.4 47394.0 48385.0 49591.8 50231.0 50720.7 7851.8 8161.3 8168.0 8185.4 8279.9 33640.0 33906.8 34870.8 35038.0 35195.8 39458.0 40762.8 40846.0 41620.2 41809.6 9334.8 9630.4 9685.2 9686.6 9757.1 10927.3 11053.2 11156.7 11208.6 11505.0 9833.6 10066.9 10260.9 10284.6 10581.6 10946.4 11080.5 11435.3 11657.2 11666.7 9449.8 9454.3 9672.3 9681.6 9828.1 8756.7 8936.6 9106.7 9187.2 9204.7 8078.8 8282.2 8779.6 8905.6 8958.2 14:55:41 INFO - PID 23823 | -------- Summary: end -------- 14:55:41 INFO - PID 23823 | 14:55:41 INFO - TEST-INFO | 23823: exit 0 14:55:41 INFO - TEST-OK | dromaeo_css | took 1122019ms 14:55:41 INFO - TEST-START | kraken 14:55:41 INFO - operating with platform_type : linux_ 14:55:41 INFO - Initialising browser for kraken test... 14:55:41 INFO - TEST-INFO | started process 24191 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAJXg50/profile http://localhost:58205/getInfo.html) 14:55:47 INFO - PID 24191 | __metrics Screen width/height:1600/1200 14:55:47 INFO - PID 24191 | colorDepth:24 14:55:47 INFO - PID 24191 | Browser inner width/height: 1024/697 14:55:47 INFO - PID 24191 | __metrics 14:55:47 ERROR - PID 24191 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 14:55:49 INFO - TEST-INFO | 24191: exit 0 14:55:49 INFO - Browser initialized. 14:55:49 INFO - Running cycle 1/1 for kraken test... 14:55:49 INFO - TEST-INFO | started process 24321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAJXg50/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:56:01 INFO - PID 24321 | RSS: Main: 154910720 14:56:01 INFO - PID 24321 | 14:56:03 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58205/tests/kraken/driver.html?testName=audio-beat-detection) 14:56:04 INFO - PID 24321 | RSS: Main: 161779712 14:56:04 INFO - PID 24321 | 14:56:07 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58205/tests/kraken/driver.html?testName=audio-dft) 14:56:08 INFO - PID 24321 | RSS: Main: 160538624 14:56:08 INFO - PID 24321 | 14:56:11 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58205/tests/kraken/driver.html?testName=audio-fft) 14:56:11 INFO - PID 24321 | RSS: Main: 157401088 14:56:11 INFO - PID 24321 | 14:56:14 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58205/tests/kraken/driver.html?testName=audio-oscillator) 14:56:14 INFO - PID 24321 | RSS: Main: 158023680 14:56:14 INFO - PID 24321 | 14:56:16 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58205/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:56:17 INFO - PID 24321 | RSS: Main: 158105600 14:56:17 INFO - PID 24321 | 14:56:20 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58205/tests/kraken/driver.html?testName=imaging-darkroom) 14:56:21 INFO - PID 24321 | RSS: Main: 163561472 14:56:21 INFO - PID 24321 | 14:56:24 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58205/tests/kraken/driver.html?testName=imaging-desaturate) 14:56:25 INFO - PID 24321 | RSS: Main: 161132544 14:56:25 INFO - PID 24321 | 14:56:28 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58205/tests/kraken/driver.html?testName=json-parse-financial) 14:56:29 INFO - PID 24321 | RSS: Main: 162689024 14:56:29 INFO - PID 24321 | 14:56:30 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58205/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:56:31 INFO - PID 24321 | RSS: Main: 162054144 14:56:31 INFO - PID 24321 | 14:56:33 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:56:34 INFO - PID 24321 | RSS: Main: 162115584 14:56:34 INFO - PID 24321 | 14:56:36 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:56:36 INFO - PID 24321 | RSS: Main: 162701312 14:56:36 INFO - PID 24321 | 14:56:39 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:56:40 INFO - PID 24321 | RSS: Main: 163409920 14:56:40 INFO - PID 24321 | 14:56:43 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:56:44 INFO - PID 24321 | RSS: Main: 166477824 14:56:44 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | Cycle 1(1): loaded http://localhost:58205/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58205/tests/kraken/driver.html?testName=ai-astar) 14:56:46 INFO - PID 24321 | RSS: Main: 167387136 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | __start_tp_report 14:56:46 INFO - PID 24321 | _x_x_mozilla_page_load 14:56:46 INFO - PID 24321 | _x_x_mozilla_page_load_details 14:56:46 INFO - PID 24321 | |i|pagename|runs| 14:56:46 INFO - PID 24321 | |0;ai-astar;113;121;118;119;123;120;120;120;121;121 14:56:46 INFO - PID 24321 | |1;audio-beat-detection;153;126;125;170;127;125;125;126;150;127 14:56:46 INFO - PID 24321 | |2;audio-dft;164;165;165;171;168;169;165;164;167;167 14:56:46 INFO - PID 24321 | |3;audio-fft;79;75;75;119;76;78;77;75;93;77 14:56:46 INFO - PID 24321 | |4;audio-oscillator;85;85;115;85;84;85;102;84;84;111 14:56:46 INFO - PID 24321 | |5;imaging-gaussian-blur;114;116;114;115;115;121;114;114;114;114 14:56:46 INFO - PID 24321 | |6;imaging-darkroom;150;150;150;150;149;150;149;150;151;150 14:56:46 INFO - PID 24321 | |7;imaging-desaturate;94;95;95;94;94;94;95;94;95;95 14:56:46 INFO - PID 24321 | |8;json-parse-financial;72;73;74;73;72;73;73;72;76;72 14:56:46 INFO - PID 24321 | |9;json-stringify-tinderbox;56;56;57;56;56;60;57;56;56;56 14:56:46 INFO - PID 24321 | |10;stanford-crypto-aes;82;85;91;81;82;83;82;85;83;83 14:56:46 INFO - PID 24321 | |11;stanford-crypto-ccm;142;140;144;141;136;135;138;137;210;144 14:56:46 INFO - PID 24321 | |12;stanford-crypto-pbkdf2;189;187;195;192;216;198;194;196;198;201 14:56:46 INFO - PID 24321 | |13;stanford-crypto-sha256-iterative;73;76;73;72;73;71;71;73;76;75 14:56:46 INFO - PID 24321 | __end_tp_report 14:56:46 INFO - PID 24321 | __start_cc_report 14:56:46 INFO - PID 24321 | _x_x_mozilla_cycle_collect,896 14:56:46 INFO - PID 24321 | __end_cc_report 14:56:46 INFO - PID 24321 | __startTimestamp1489615006411__endTimestamp 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | ------- Summary: start ------- 14:56:46 INFO - PID 24321 | Number of tests: 14 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#0] ai-astar Cycles:10 Average:119.60 Median:120.50 stddev:2.67 (2.2%) stddev-sans-first:1.41 14:56:46 INFO - PID 24321 | Values: 113.0 121.0 118.0 119.0 123.0 120.0 120.0 120.0 121.0 121.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:127.00 stddev:16.20 (12.8%) stddev-sans-first:15.88 14:56:46 INFO - PID 24321 | Values: 153.0 126.0 125.0 170.0 127.0 125.0 125.0 126.0 150.0 127.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#2] audio-dft Cycles:10 Average:166.50 Median:167.00 stddev:2.32 (1.4%) stddev-sans-first:2.28 14:56:46 INFO - PID 24321 | Values: 164.0 165.0 165.0 171.0 168.0 169.0 165.0 164.0 167.0 167.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#3] audio-fft Cycles:10 Average:82.40 Median:77.00 stddev:13.93 (18.1%) stddev-sans-first:14.72 14:56:46 INFO - PID 24321 | Values: 79.0 75.0 75.0 119.0 76.0 78.0 77.0 75.0 93.0 77.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:84.50 stddev:12.37 (14.6%) stddev-sans-first:12.86 14:56:46 INFO - PID 24321 | Values: 85.0 85.0 115.0 85.0 84.0 85.0 102.0 84.0 84.0 111.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:114.50 stddev:2.18 (1.9%) stddev-sans-first:2.28 14:56:46 INFO - PID 24321 | Values: 114.0 116.0 114.0 115.0 115.0 121.0 114.0 114.0 114.0 114.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 14:56:46 INFO - PID 24321 | Values: 150.0 150.0 150.0 150.0 149.0 150.0 149.0 150.0 151.0 150.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 14:56:46 INFO - PID 24321 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#8] json-parse-financial Cycles:10 Average:73.00 Median:73.00 stddev:1.25 (1.7%) stddev-sans-first:1.27 14:56:46 INFO - PID 24321 | Values: 72.0 73.0 74.0 73.0 72.0 73.0 73.0 72.0 76.0 72.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#9] json-stringify-tinderbox Cycles:10 Average:56.60 Median:56.00 stddev:1.26 (2.3%) stddev-sans-first:1.32 14:56:46 INFO - PID 24321 | Values: 56.0 56.0 57.0 56.0 56.0 60.0 57.0 56.0 56.0 56.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.00 stddev:2.87 (3.5%) stddev-sans-first:2.98 14:56:46 INFO - PID 24321 | Values: 82.0 85.0 91.0 81.0 82.0 83.0 82.0 85.0 83.0 83.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:141.50 stddev:22.47 (15.9%) stddev-sans-first:23.76 14:56:46 INFO - PID 24321 | Values: 142.0 140.0 144.0 141.0 136.0 135.0 138.0 137.0 210.0 144.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.60 Median:197.00 stddev:8.03 (4.1%) stddev-sans-first:8.03 14:56:46 INFO - PID 24321 | Values: 189.0 187.0 195.0 192.0 216.0 198.0 194.0 196.0 198.0 201.0 14:56:46 INFO - PID 24321 | 14:56:46 INFO - PID 24321 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:73.00 stddev:1.83 (2.5%) stddev-sans-first:1.94 14:56:46 INFO - PID 24321 | Values: 73.0 76.0 73.0 72.0 73.0 71.0 71.0 73.0 76.0 75.0 14:56:46 INFO - PID 24321 | -------- Summary: end -------- 14:56:46 INFO - PID 24321 | 14:56:47 INFO - TEST-INFO | 24321: exit 0 14:56:47 INFO - TEST-OK | kraken | took 65718ms 14:56:47 INFO - SUITE-END | took 1187s 14:56:48 INFO - Completed test suite (00:19:48) 14:56:48 INFO - javascript benchmark 14:56:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6719.331592621184, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2673.6390587270716, 2683.2084535062154, 2687.0460986349844, 2715.9456810863794, 2724.5232084385234, 4933.7779799909, 4938.950610493894, 4991.326170966923, 5019.824306149286, 5068.062408454434, 4989.950100498994, 4999.0, 5011.473795251501, 5014.623903207265, 5101.693898366091, 4552.90894182117, 4557.339185818055, 4599.4710608279975, 4602.930956035662, 4630.652701047435, 4606.032733126048, 4651.348811166439, 4658.650601204923, 4666.136764698542, 4666.160091183586, 4499.505054444008, 4517.638588912912, 4563.0, 4568.680192386534, 4630.513796051399, 4566.4520257568975, 4585.082983403333, 4589.747563883985, 4623.653226008063, 4629.930551041737, 4534.614557762561, 4540.954590454086, 4547.658925580562, 4566.292224705143, 4581.106684196583, 4896.706197628154, 4927.1870141426625, 4943.604511639096, 4999.150144475448, 5002.9499705002845, 5380.273663055476, 5389.461053894618, 5407.83776486706, 5429.104197807357, 5456.699881506515, 5348.903474787659, 5358.705271210082, 5364.678119312854, 5365.08793505105, 5379.273798037254, 4887.437944636341, 4973.52751488608, 5008.449070602231, 5009.549140577293, 5071.2139618359215, 5334.0665383557725, 5348.411674715778, 5371.435999220025, 5387.0, 5426.9185962210595, 5172.379314482211, 5243.056249875059, 5246.108161612458, 5248.029114613809, 5252.080885844962, 5251.737413129328, 5296.947030529723, 5325.86685332862, 5334.466553344634, 5344.198370244494, 5202.531772140525, 5221.216817477409, 5275.076861549213, 5277.393099793496, 5280.155175171954, 5248.029114613809, 5264.842054738364, 5280.7359632019015, 5334.786608535693, 5343.973280133652, 4512.120136573402, 4526.637868970474, 4529.297958816389, 4534.546545345505, 4535.9546404536195, 3927.430522574268, 3941.881743547698, 3949.3681011038097, 3949.782761948063, 3960.663343615767, 4527.139843429738, 4600.769961501912, 4605.424321959755, 4607.723536587815, 4641.280601506772, 5337.919931201035, 5340.092184328596, 5365.195220716923, 5368.409474957752, 5376.8118115865755, 4582.9770851146195, 4594.218982772936, 4595.770211489412, 4596.655250856199, 4600.700954437951, 4771.260454629538, 4771.308160316735, 4777.355057067251, 4780.737059461763, 4797.376341075638, 5042.974785126051, 5053.166227572483, 5060.822871199491, 5062.696238225718, 5064.670796398223, 5326.866828329323, 5362.410134885237, 5387.111126664056, 5431.83704488866, 5432.103702888979, 5046.016026874871, 5047.2681461187185, 5048.949510504848, 5093.1341671915125, 5097.439281679087, 5271.156614941591, 5309.65487243328, 5310.9468905312, 5320.521153096085, 5365.248865158959, 5259.973700131475, 5332.33345831771, 5364.597655175956, 5406.135018397038, 5407.891842163056, 5218.269442278008, 5230.0, 5255.710935898563, 5295.099833028317, 5307.363116426053, 5184.948150518447, 5192.532672059533, 5195.272661827423, 5211.218317252443, 5233.528982391603, 5057.69653820772, 5106.336176297058, 5116.74416279192, 5118.206677964921, 5164.406093299169, 5133.820316288912, 5134.640575159852, 5148.459411761861, 5161.896762064813, 5168.302279192261, 5023.321851549994, 5028.044671512401, 5028.069807085701, 5029.4719054498755, 5033.094043072282], "value": 4891.418618368311, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.5598746895971, 677.4546490075488, 706.6466766616693, 845.6701886264358, 858.527809704663, 34769.30461390774, 35469.2906141877, 35486.290274194536, 35488.64511354889, 35576.0, 31512.369752604933, 31970.36059278813, 32065.19837004076, 32073.0, 32113.518297225502, 6216.84457888553, 8610.956945215265, 8793.648254069829, 8811.911880881173, 8827.514486703229, 9150.084991500864, 9226.261899048093, 9258.444493330388, 9281.58232879522, 9284.210842078432, 9230.538473076353, 9288.907110928905, 9327.253819694442, 9356.111169438926, 9356.391834530734, 9170.862437063415, 9257.86113208299, 9308.0, 9312.34813563051, 9325.580348884316, 8977.326700497424, 9012.8648070279, 9042.050584688644, 9065.365424420293, 9069.047749986288, 34754.30491390158, 34756.47865282023, 34782.65217347819, 34804.82597587021, 34883.127921801904, 3322.6843449872467, 3388.203772113551, 3394.6775056369606, 3434.227298857762, 3450.189205536697, 24591.262262132164, 24694.431530166978, 24825.62761558578, 25520.36199095019, 25558.10546630859, 3021.5165573508352, 3109.8289594072317, 3129.749620030392, 3221.629512606057, 3224.8548815303366, 111162.0, 112094.43952780265, 139993.0, 155585.0, 155680.0, 41329.58670413317, 44648.776756116014, 44972.775136124765, 45021.0, 45208.321875171896, 46572.301415478796, 46638.0, 46830.0, 46848.29727554089, 46868.296975545396, 12979.35103244834, 12983.870161298455, 13067.150635208682, 13073.0, 13140.671483212996, 10328.896711032943, 10361.740956475998, 10380.42907640072, 10424.426656533811, 10509.789804204025, 13674.93162534181, 13691.0, 13701.588952331445, 13857.099288546213, 13930.795916939162, 5677.63095398806, 5703.800366987136, 5713.171590119409, 5739.67543145027, 5826.621269617462, 938.4181807279531, 981.087588542648, 982.1995074014656, 993.1359717046216, 996.566493575297, 24126.758732412804, 24214.87892560526, 24406.389840254134, 24437.38906527351, 24449.266522004367, 1026.810040142576, 1032.0608246495653, 1040.167865707431, 1064.893510648929, 1065.0627447846, 12487.75024499505, 12888.93555532235, 12944.09391342597, 12967.416466259041, 13002.674933126558, 208248.0, 212240.0, 213656.0, 214364.0, 231667.0, 45464.77267613707, 45979.0, 46047.539524604996, 46062.07875842464, 46297.768511157905, 1197.7484728207, 1226.5277868020876, 1226.6381417481864, 1250.0874361715817, 1261.9810702839466, 24417.26748197557, 24592.631110533, 25667.743322566534, 25708.871455642602, 25811.0, 36754.448683270304, 37003.25993480169, 37161.256774864894, 37186.44220336643, 37195.442068369535, 235462.0, 237282.0, 238038.0, 238056.0, 238899.0, 22071.227507037176, 22380.0, 24784.0, 25231.87384063068, 29022.709772902002, 32293.192670183435, 32298.192545186557, 32359.352812944082, 32566.348673025932, 32810.17974550655, 27777.861110694317, 27799.44401111926, 27801.58297625497, 27853.442931141668, 28388.716112838607, 27572.0, 27686.723132768413, 28163.0, 28493.0, 28704.856475717486, 27888.44223115567, 28138.577921331605, 28173.154805355873, 28299.15102546927, 30657.54013689841, 22994.310170694906, 25034.74965250397, 26183.345416364744, 26233.344166395993, 29426.852865735535, 23389.649155262345, 23489.76510234944, 23509.52980940406, 23834.761652383255, 23936.162234321717], "value": 15774.709717233647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.50975995845346, 398.5297349128028, 411.1817483208415, 413.11387074724695, 433.18993482188307, 12583.874161258385, 17576.73634895476, 18458.72311915321, 18473.907630461847, 18606.627867442643, 17211.82788172118, 17364.479065628053, 17509.91245043776, 17517.649647007052, 17628.735568966476, 5314.495122963332, 5558.110702287635, 5595.580331475136, 6710.16122984627, 6976.127984002, 6784.253732089466, 6819.829504262383, 6836.0, 6862.348076932683, 6862.7941161764975, 6792.796216113525, 6857.759978400732, 6859.07402500661, 7009.404200642926, 7019.719211231544, 6888.896666550006, 6916.96245563162, 6954.095967524189, 7047.788566343018, 7070.540414873017, 6776.864462710718, 6830.248672646005, 6861.245263021114, 6863.553868998499, 6879.071325371111, 21244.681329780065, 21763.041326280712, 22213.0, 22415.887920560457, 22496.88751556248, 1999.9700004499944, 2493.8628375439343, 2572.266903932389, 2573.266619013591, 2580.87095645217, 13642.795358069638, 13910.791338129937, 14006.0, 14940.32768525419, 15083.47207847731, 1557.4315374888083, 1640.3274657390414, 1660.0786563457277, 1664.716998110324, 1693.2191970831852, 84079.57960210221, 90924.54537727269, 93975.53012234895, 94097.529512352, 95038.52480737552, 16056.839431605535, 16539.91730041366, 16691.24889379981, 17190.50737643035, 17880.374186903395, 25908.740912590634, 28454.857725711237, 28571.28571785722, 28645.427091458056, 28931.42137157245, 8708.390412671179, 8733.170348816851, 8847.911520884838, 8861.290677110826, 8990.415622984485, 7522.247775222434, 7571.621418929031, 7737.574433406104, 7751.379889608817, 7764.417668674759, 8672.696455624024, 8795.912040879637, 9033.864492032624, 9108.49903255314, 9209.63161473547, 3656.8902932912056, 3759.793211373346, 3763.6989040876665, 3814.9427758583643, 3837.1174629834736, 810.2869474862084, 829.87966245568, 836.2264904963007, 837.5226121110908, 842.5408152556871, 18177.72733409, 19119.13963871629, 19239.326623568108, 19258.518537036685, 19472.123754431086, 821.1870248453988, 826.8553003224412, 839.3914412051214, 846.4582667092942, 850.6724910909221, 9720.805583888425, 10675.199360048027, 10757.218122242553, 10785.687061630277, 10792.47823977333, 209800.0, 210398.0, 210525.0, 210935.0, 245826.0, 86154.0, 89085.0, 90474.54762726276, 90798.0, 94342.52828735813, 1122.966311010671, 1140.8231724082614, 1146.2377518949804, 1146.896779289868, 1151.2804497189256, 15637.168893620681, 16086.43697470583, 16166.434174803826, 16675.74986375181, 16755.497335079966, 38406.0, 42134.36798448087, 43081.353779692705, 43769.78115109404, 44849.77575112104, 50781.23828142507, 51565.742171288905, 51895.22157167721, 52101.21848172205, 52464.47535524596, 7745.380369570534, 9981.600735970771, 10085.142762864945, 10139.7465063374, 10209.846852297072, 11736.119791015537, 11846.822297665714, 12001.339926304137, 12069.456874440495, 12356.62930112098, 10127.898721012696, 10300.433476158583, 10319.742006449898, 10475.476226188812, 10721.195910306915, 9797.167240784329, 9830.213582913208, 9853.261005424765, 9914.454704991007, 10286.537105830106, 9840.261980351646, 9898.703038908845, 9983.900160998588, 10106.39361638304, 10342.172626189737, 9368.266870628955, 9817.85273220888, 10020.590939986383, 10141.797164056825, 10485.16118710486, 11358.886411135783, 11362.318260904372, 11380.886191138314, 11479.139064570358, 11883.108766842348], "value": 9689.53473544618, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.8840092792577, 1476.3356489579685, 1483.9035462694917, 1514.2958524286207, 1563.7967064281643, 8169.91830081699, 8480.32157427406, 8803.559822008898, 9132.086791320873, 9284.118008789163, 8588.656453741844, 8817.647294108228, 8881.91118088819, 8895.599698013588, 8902.376833621633, 2009.9598008039868, 2046.1610739596695, 2051.1590247998324, 2052.866563673364, 2053.045333919727, 1968.7932767059467, 2005.8595898287126, 2013.1745984146503, 2017.5662232619984, 2021.675802349456, 1985.5929534445404, 1992.3823614679495, 2001.0795034284174, 2001.279539365827, 2001.8098280663387, 1934.7001214811796, 1937.9709304360447, 1941.7184508246296, 1943.232423192845, 1948.7174359717833, 1875.3904980881268, 1875.7749070111674, 1881.416760804155, 2023.7672667643262, 2042.4893776555862, 9417.0, 9418.10527999839, 9498.857517137249, 9510.857337139949, 9523.285753568442, 4520.50274469808, 4687.507811679793, 4700.224462963607, 4704.45898721648, 4711.835085772016, 2551.329853343276, 2625.1599488163797, 2639.4061336199393, 2661.2681512584, 2726.359305563191, 961.711486554031, 974.4543055888654, 1025.0825511167463, 1030.5414090729628, 1035.9326643768131, 13308.0, 13387.196768193939, 13422.798658020138, 13445.731085378238, 13446.12600180995, 3744.812759362021, 3850.537935447764, 3957.9010524736536, 3973.165635216637, 4008.6392224699916, 11588.188826782212, 11650.0, 11656.592019279284, 11683.474243658886, 11698.88301116995, 4568.246239370533, 4614.238650622677, 4631.930521042187, 4639.234526303122, 4669.0, 3619.2037751694584, 3688.967089215024, 4173.603507666767, 4219.746815191098, 4244.384564238168, 3356.47974563943, 3366.713829324535, 3536.8054756988613, 3536.840842162109, 3580.695640870555, 926.8192702422963, 949.715085474355, 959.5346257065318, 966.1546147121269, 970.5001924009141, 843.4180415513276, 847.9957600211959, 856.9957150214209, 879.2087121590491, 880.6521424037597, 5695.060315047971, 5757.251557297608, 5812.796552120655, 5916.5742045761035, 5923.881522369615, 904.6607522179182, 907.120093509295, 913.2876356441986, 913.881195444588, 916.8716379706847, 3622.3298689742483, 3640.543782486973, 3672.0, 3707.6663100321102, 3760.4735337052834, 10826.566937322576, 10888.564457421773, 11234.76417594064, 11461.083113350913, 11463.541458341573, 11213.0, 11243.044341230923, 11259.774804503864, 11277.887221127683, 11289.0, 8137.186281371815, 8463.280621147149, 8586.356023298278, 8587.441816281796, 8603.956980215058, 6028.939710602838, 6034.306054803578, 6097.932861749087, 6128.325884152829, 6259.812205633838, 9632.518374081128, 10775.838362424727, 10806.243562950518, 10874.836877447004, 10973.341599504056, 10079.143272821892, 10130.645427410005, 10187.439690817077, 10199.898001019896, 10214.0, 7595.658195381111, 7726.227377262454, 7769.53382797034, 8555.401121921655, 8778.780530486789, 8688.30493560501, 8945.910540894769, 8946.507942062988, 8970.013298537026, 8973.37186396946, 7749.535027898344, 7789.844203116019, 7938.126806051445, 8162.591870406574, 8310.83378332442, 7557.244275572619, 7598.962005189938, 7623.428242881917, 7654.655540500579, 7925.207479252029, 6871.0, 6991.056207412138, 7098.112735908012, 7100.502964792415, 7120.608366539683, 5034.471380504994, 5114.693118412907, 5148.30497882781, 5163.66436181647, 5255.448177941414, 5076.543111120017, 5187.19598462239, 5199.246109314204, 5216.2436446714255, 5384.7576859040655], "value": 4406.869465343422, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.06790074204105, 354.9059499232702, 355.1671330729442, 367.7444176297474, 374.1133513572832, 5168.5606723428555, 5333.679979201251, 5388.568914486837, 5398.81104161355, 5427.1316589345715, 5110.6422550421485, 5208.76560554775, 5276.3668359796875, 5286.154215325549, 5300.602454815885, 4524.932126018112, 4601.7469039203, 4607.239805432117, 4612.607928326096, 4613.077384523092, 4499.627613577857, 4548.5224051474615, 4569.4516658001085, 4605.078984203156, 4646.186917289478, 4540.432445944257, 4564.954350456486, 4584.19776539106, 4591.380163677911, 4601.079784043188, 4569.0, 4577.908441831145, 4578.931316030245, 4582.862514124582, 4583.106294272585, 4316.395704601358, 4395.164918665475, 4425.358323043173, 4431.9778401108115, 4461.397711308964, 5158.871028224287, 5170.767315470813, 5177.715225662587, 5216.608754343401, 5233.528982391565, 5337.572994160458, 5346.198070289449, 5373.76403427214, 5375.650582712111, 5421.701806400646, 5473.01485732568, 5653.943460565424, 5662.801801936913, 5664.6601203927485, 5678.119891416837, 4863.489333619955, 4869.756512174377, 4873.34209881665, 4891.388576427947, 4902.877428064291, 5639.379668236491, 5683.488486036235, 5804.854878628026, 5818.854528636818, 5888.322842873082, 4981.3026176334615, 5070.61970352225, 5071.771770270347, 5086.3133476980875, 5095.566876815438, 5136.8202112925865, 5180.4560521145495, 5189.4711596115, 5197.0, 5203.557697595671, 5118.0, 5339.786408543692, 5366.704831234241, 5379.220013098079, 5429.1856221566295, 5679.176519404663, 5685.175649530877, 5686.14707793826, 5687.544996400278, 5689.715514224272, 1758.2439550993195, 1826.5433641589602, 1860.981390186108, 1865.1420346640425, 1870.4949663590755, 1446.0, 1472.9189894555902, 1489.6127006978263, 1519.5061604978425, 1522.6726253855475, 4783.880402989953, 4815.7351345676325, 4825.4933232010835, 4860.854174374775, 4861.975690121527, 5683.715814209273, 5691.77232910679, 5694.914576281359, 5708.914366284509, 5736.368999410061, 4671.6262698984, 4828.0, 4858.975705121452, 4876.926846097312, 4887.193613053877, 3060.7551395888277, 3083.290843106053, 3089.8300593467575, 3098.10155055036, 3099.7210251077513, 5373.0, 5378.78484860609, 5383.273258110105, 5385.75764090609, 5403.567714582824, 5515.889682206413, 5926.111083337534, 5936.376680448577, 5992.131140984533, 5999.520038397005, 5442.4829641184815, 5444.945550544444, 5447.128459446549, 5462.6995515247045, 5483.8080667176455, 5722.798212375448, 5847.210626565359, 5873.471387575139, 5875.094296262669, 5896.64620122794, 5588.608797384144, 5640.125780504029, 5677.8864422710485, 5737.512311453406, 5756.30924289088, 5401.351837779492, 5405.324334458192, 5439.428859969805, 5447.1829225616475, 5453.918191227055, 4600.194965880891, 4635.745034023161, 4636.03943780152, 4666.8133274668635, 4671.486136525047, 5709.8287051388525, 5781.248437703071, 5825.0679891217205, 5840.2991641003355, 5862.179294898632, 5637.210790489253, 5643.463870932255, 5644.294463192101, 5657.4908258256955, 5664.320281566238, 5466.3713672928125, 5630.85922851932, 5637.46444087811, 5641.689707066151, 5671.744771485375, 5538.418466061005, 5572.164175373727, 5576.10782274834, 5585.441455854382, 5615.241942337732, 5701.657900525982, 5705.201271822031, 5710.5146062585145, 5727.198192253171, 5744.678723893611, 5383.353997520323, 5528.281323427928, 5528.308961379827, 5572.526335261548, 5586.553075754011], "value": 4512.10378721739, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.6754406568749, 678.3521736741412, 724.3263764698828, 736.7789663101069, 744.3301029073833, 12410.689732756675, 13130.999999999987, 13148.041690619617, 13192.208467491933, 13194.736105277889, 12039.398030098482, 12115.819152552536, 12186.573469928555, 12190.207636503621, 12203.328816915066, 812.528733334666, 825.793551612097, 853.9103394143618, 857.8756080368343, 858.51064893011, 854.3037424499014, 854.4403415762686, 854.5684429363163, 856.1267507142711, 857.5626430520417, 891.9598618062203, 892.857142857143, 893.2452077994085, 893.852514335134, 894.838928992781, 881.0924747510074, 883.4875772052194, 889.2485849457263, 889.6975028490313, 894.4633220067972, 651.775137577535, 655.6000839487908, 655.6983787457775, 656.7635651165576, 658.1871388834785, 13387.665308367272, 13499.797503037462, 13567.118137321142, 13862.168269903737, 14748.410063597443, 13589.864101359057, 14409.71180576393, 14438.92780536101, 14484.92757536216, 14500.41998320066, 10315.948420257926, 10368.289232276666, 10385.948070259676, 10448.582056717722, 10520.0, 3150.921226969321, 3156.384505021522, 3192.409404260219, 3201.6478187399366, 3318.0875259303884, 63840.0, 65034.67482662604, 65662.3433765661, 66065.66967165134, 66351.33648663548, 10342.327748696462, 10354.0, 10470.633527826489, 10508.737281567868, 10538.525766340534, 21074.78925210759, 21357.78642213558, 21526.56946861054, 21630.56738865214, 21642.134314627237, 6630.635315057718, 6668.099806526154, 6702.329767023259, 6704.564203326768, 6962.860742785116, 3404.914877128042, 3407.676270754324, 3446.7414943879307, 3486.651334866493, 3520.612732599412, 2793.189974907254, 2797.59434881941, 2827.5899994500683, 2842.2325971987448, 2868.0, 446.1990726645679, 448.3879504476366, 454.5590776946357, 455.0603004795088, 461.6606794006403, 649.8472858878159, 654.5295630841437, 668.5859407353413, 673.737242475435, 688.3082502085372, 8633.352498562634, 8806.339524535557, 9076.773080673036, 9478.857817132748, 9703.951480242553, 581.8283606336141, 616.4444753273314, 626.683524819963, 626.9749210031639, 628.7830698409042, 4070.6539944105084, 4144.772037537904, 4214.1571685662375, 4229.513605935295, 4367.126574685076, 49557.50442495601, 60608.0, 60736.69631651814, 61729.0, 62189.378106218355, 47223.291650625266, 53552.0, 54907.72546137324, 54917.72541137269, 55898.44101558932, 47394.0, 48385.0, 49591.75204124028, 50231.0, 50720.74639626852, 7851.803704907423, 8161.306288965505, 8168.0, 8185.427020108774, 8279.875801863098, 33640.0, 33906.83046584751, 34870.825645871606, 35038.0, 35195.82402087973, 39458.0, 40762.79618602007, 40846.0, 41620.167596648505, 41809.581904180566, 9334.766630834283, 9630.374025688589, 9685.225181985283, 9686.564104615169, 9757.114355762444, 10927.344359338465, 11053.17101217428, 11156.721081972692, 11208.55165793359, 11505.0, 9833.60665573369, 10066.899331006596, 10260.897391025994, 10284.640037598649, 10581.629642962767, 10946.39794811293, 11080.501377438195, 11435.313881167156, 11657.242279251821, 11666.650000499998, 9449.811003780023, 9454.338196326466, 9672.274579406432, 9681.612735490502, 9828.11546960777, 8756.737297881074, 8936.553172341228, 9106.681266155652, 9187.173154416134, 9204.723858284262, 8078.798030049295, 8282.213189746964, 8779.604917778843, 8905.599248033983, 8958.193762561401], "value": 6190.272358659218, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 121.0, 118.0, 119.0, 123.0, 120.0, 120.0, 120.0, 121.0, 121.0], "value": 119.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 126.0, 125.0, 170.0, 127.0, 125.0, 125.0, 126.0, 150.0, 127.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 165.0, 171.0, 168.0, 169.0, 165.0, 164.0, 167.0, 167.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 75.0, 119.0, 76.0, 78.0, 77.0, 75.0, 93.0, 77.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 115.0, 85.0, 84.0, 85.0, 102.0, 84.0, 84.0, 111.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 114.0, 115.0, 115.0, 121.0, 114.0, 114.0, 114.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 149.0, 150.0, 149.0, 150.0, 151.0, 150.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 74.0, 73.0, 72.0, 73.0, 73.0, 72.0, 76.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 56.0, 56.0, 60.0, 57.0, 56.0, 56.0, 56.0], "value": 56.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 91.0, 81.0, 82.0, 83.0, 82.0, 85.0, 83.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 144.0, 141.0, 136.0, 135.0, 138.0, 137.0, 210.0, 144.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 187.0, 195.0, 192.0, 216.0, 198.0, 194.0, 196.0, 198.0, 201.0], "value": 196.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 73.0, 72.0, 73.0, 71.0, 71.0, 73.0, 76.0, 75.0], "value": 73.3, "unit": "score"}]}]} 14:56:48 INFO - Return code: 0 14:56:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:56:48 ERROR - # TBPL SUCCESS # 14:56:48 INFO - Running post-action listener: _resource_record_post_action 14:56:48 INFO - [mozharness: 2017-03-15 21:56:48.238175Z] Finished run-tests step (success) 14:56:48 INFO - Running post-run listener: _resource_record_post_run 14:56:48 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 0; Write bytes: 360800256; Read time: 0; Write time: 332508 14:56:48 INFO - TinderboxPrint: CPU usage
13.3% 14:56:48 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:56:48 INFO - TinderboxPrint: I/O write bytes / time
360,800,256 / 332,508 14:56:48 INFO - TinderboxPrint: CPU idle
8,328.6 (86.7%) 14:56:48 INFO - TinderboxPrint: CPU user
1,220.0 (12.7%) 14:56:48 INFO - TinderboxPrint: Swap in / out
0 / 0 14:56:48 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105930752; Read time: 0; Write time: 120820 14:56:48 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 254869504; Read time: 0; Write time: 211688 14:56:48 INFO - Running post-run listener: _upload_blobber_files 14:56:48 INFO - Blob upload gear active. 14:56:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:56:48 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 14:56:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:56:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:56:49 INFO - (blobuploader) - INFO - Open directory for files ... 14:56:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:56:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:56:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:50 INFO - (blobuploader) - INFO - Done attempting. 14:56:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:56:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:56:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:50 INFO - (blobuploader) - INFO - Done attempting. 14:56:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:56:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:56:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:51 INFO - (blobuploader) - INFO - Done attempting. 14:56:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:56:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:56:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:56:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:56:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:56:54 INFO - (blobuploader) - INFO - Done attempting. 14:56:54 INFO - (blobuploader) - INFO - Iteration through files over. 14:56:54 INFO - Return code: 0 14:56:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:56:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:56:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f288ae7b10c5fad5544ae56b0b38dde8eeb7c9e48d169d0bc841726f36d04377989a6e4e12310117f479374cd903a63e776abb5cbefac2201af1d9762f6b9d04", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9321f982249ad683c41412f23cdebcee75915080973a1dcc677c32ccd5329424fcd2ef9967d7690d50c3610b90226442d2d9c4c94dbc1777167fa58cd556bcbf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84848650417867fa08a3dc7e0476b2e887a3fcca460475192304c30032237562fb1fa7eb6e0892d5c19f5b248209c33a68df3ac02f867e16943ed92b75300bf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815b0feac8a1a2ea15bd84a813d0dc14dd583c29fcff22dba1d11795b97e6b5ee8826b151457ce353517f1950ddfc082f0c5114632e62ed88549e6a4711eb2ff"} 14:56:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:56:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:56:54 INFO - Contents: 14:56:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f288ae7b10c5fad5544ae56b0b38dde8eeb7c9e48d169d0bc841726f36d04377989a6e4e12310117f479374cd903a63e776abb5cbefac2201af1d9762f6b9d04", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9321f982249ad683c41412f23cdebcee75915080973a1dcc677c32ccd5329424fcd2ef9967d7690d50c3610b90226442d2d9c4c94dbc1777167fa58cd556bcbf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84848650417867fa08a3dc7e0476b2e887a3fcca460475192304c30032237562fb1fa7eb6e0892d5c19f5b248209c33a68df3ac02f867e16943ed92b75300bf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815b0feac8a1a2ea15bd84a813d0dc14dd583c29fcff22dba1d11795b97e6b5ee8826b151457ce353517f1950ddfc082f0c5114632e62ed88549e6a4711eb2ff"} 14:56:54 INFO - Running post-run listener: copy_logs_to_upload_dir 14:56:54 INFO - Copying logs to upload dir... 14:56:54 INFO - mkdir: /builds/slave/test/build/upload/logs 14:56:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.163294 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-15 14:56:54.182166) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 14:56:54.185566) ========= 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=781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f288ae7b10c5fad5544ae56b0b38dde8eeb7c9e48d169d0bc841726f36d04377989a6e4e12310117f479374cd903a63e776abb5cbefac2201af1d9762f6b9d04", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9321f982249ad683c41412f23cdebcee75915080973a1dcc677c32ccd5329424fcd2ef9967d7690d50c3610b90226442d2d9c4c94dbc1777167fa58cd556bcbf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84848650417867fa08a3dc7e0476b2e887a3fcca460475192304c30032237562fb1fa7eb6e0892d5c19f5b248209c33a68df3ac02f867e16943ed92b75300bf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815b0feac8a1a2ea15bd84a813d0dc14dd583c29fcff22dba1d11795b97e6b5ee8826b151457ce353517f1950ddfc082f0c5114632e62ed88549e6a4711eb2ff"} build_url:https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/d9EGGQ74Qc-2LM-H7UPtog/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f288ae7b10c5fad5544ae56b0b38dde8eeb7c9e48d169d0bc841726f36d04377989a6e4e12310117f479374cd903a63e776abb5cbefac2201af1d9762f6b9d04", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9321f982249ad683c41412f23cdebcee75915080973a1dcc677c32ccd5329424fcd2ef9967d7690d50c3610b90226442d2d9c4c94dbc1777167fa58cd556bcbf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84848650417867fa08a3dc7e0476b2e887a3fcca460475192304c30032237562fb1fa7eb6e0892d5c19f5b248209c33a68df3ac02f867e16943ed92b75300bf8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815b0feac8a1a2ea15bd84a813d0dc14dd583c29fcff22dba1d11795b97e6b5ee8826b151457ce353517f1950ddfc082f0c5114632e62ed88549e6a4711eb2ff"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-15 14:56:54.211039) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 14:56:54.211403) ========= 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=781b77c4c33858b1f599d705000001c1-1489613750.221870-1215253536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-15 14:56:54.296962) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 14:56:54.297290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-15 14:56:54.297598) ========= ========= Total master_lag: 0.29 =========